Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/1030/

1 tests failed.
FAILED:  org.apache.solr.TestDistributedSearch.test

Error Message:


Stack Trace:
java.lang.NullPointerException
        at 
__randomizedtesting.SeedInfo.seed([DA3F7411FFE8837F:526B4BCB5114EE87]:0)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:568)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:986)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939)
        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:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        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:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        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:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11014 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/init-core-data-001
   [junit4]   2> 2164383 INFO  
(SUITE-TestDistributedSearch-seed#[DA3F7411FFE8837F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 2164383 INFO  
(SUITE-TestDistributedSearch-seed#[DA3F7411FFE8837F]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2164666 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1
   [junit4]   2> 2164667 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] o.e.j.s.Server 
jetty-9.2.13.v20150730
   [junit4]   2> 2164669 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@174bee47{/,null,AVAILABLE}
   [junit4]   2> 2164669 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@44e1e24c{HTTP/1.1}{127.0.0.1:47605}
   [junit4]   2> 2164669 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] o.e.j.s.Server 
Started @2167062ms
   [junit4]   2> 2164669 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores,
 hostPort=47605, hostContext=/}
   [junit4]   2> 2164669 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 2164669 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control'
   [junit4]   2> 2164670 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2164670 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 2164670 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/solr.xml
   [junit4]   2> 2164684 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores
   [junit4]   2> 2164684 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1951022864
   [junit4]   2> 2164684 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control]
   [junit4]   2> 2164684 WARN  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/lib
   [junit4]   2> 2164684 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
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> 2164685 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2164686 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2164686 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2164686 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 2164686 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2164687 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoresLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores
   [junit4]   2> 2164687 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: {dataDir=data/, 
configSetProperties=configsetprops.json, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1,
 loadOnStartup=true, transient=false, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1/,
 config=solrconfig.xml, configSet=collection1, schema=schema.xml, 
name=collection1}
   [junit4]   2> 2164687 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1/
   [junit4]   2> 2164687 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 2164688 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 2164688 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2
   [junit4]   2> 2164688 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2164688 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2164688 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 2164688 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 2164688 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 2164688 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 2164715 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2164734 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 2164777 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2164784 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2165020 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2165027 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2165029 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2165033 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2165036 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2165037 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2165038 WARN  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2165038 WARN  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2165038 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2165038 WARN  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2165038 WARN  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2165039 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 2165040 INFO  (coreLoadExecutor-4498-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1
   [junit4]   2> 2165040 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2165040 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1],
 dataDir=[null]
   [junit4]   2> 2165040 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19efd160
   [junit4]   2> 2165041 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1/data
   [junit4]   2> 2165041 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1/data/index/
   [junit4]   2> 2165041 WARN  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2165041 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1/data/index
   [junit4]   2> 2165041 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=1.17578125, 
floorSegmentMB=1.2587890625, forceMergeDeletesPctAllowed=25.58422740531904, 
segmentsPerTier=5.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.532752684939979
   [junit4]   2> 2165043 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-002
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_1,generation=1}
   [junit4]   2> 2165043 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2165044 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-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1/data/
   [junit4]   2> 2165044 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/control/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 2165047 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 2165047 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 2165048 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 2165050 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2165052 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2165053 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2165054 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2165057 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 2165059 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/schema,defaults,/get,/config,lazy,mltrh,/replication,tvrh,/debug/dump,/admin/segments,spellCheckCompRH,/update/json,/admin/properties,spellCheckCompRH_Direct,/admin/file,/admin/logging,/terms,/update/json/docs,/search-facet-def,/admin/threads,/search-facet-invariants,/admin/ping,/mlt,standard,/update,/update/csv,/admin/system,mock,spellCheckWithWordbreak,/admin/mbeans,spellCheckWithWordbreak_Direct,/admin/plugins,spellCheckCompRH1,dismax,/admin/luke
   [junit4]   2> 2165060 WARN  (coreLoadExecutor-4498-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> 2165061 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2165061 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 2165061 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 2165062 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 2165063 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-002
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_1,generation=1}
   [junit4]   2> 2165063 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2165063 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@483824f8[collection1] 
main
   [junit4]   2> 2165063 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 2165063 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 2165063 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2165063 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2165072 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2165099 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2165099 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2165099 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2165099 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2165099 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2165100 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2165100 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2165100 INFO  (coreLoadExecutor-4498-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2165100 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2165100 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2165101 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2165101 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@483824f8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2165445 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 2165447 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] o.e.j.s.Server 
jetty-9.2.13.v20150730
   [junit4]   2> 2165448 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2cd230f9{/,null,AVAILABLE}
   [junit4]   2> 2165448 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@5703ce95{HTTP/1.1}{127.0.0.1:55549}
   [junit4]   2> 2165449 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] o.e.j.s.Server 
Started @2167841ms
   [junit4]   2> 2165449 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=55549, hostContext=/, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores}
   [junit4]   2> 2165449 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 2165449 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0'
   [junit4]   2> 2165449 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2165449 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 2165449 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 2165472 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores
   [junit4]   2> 2165473 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 348463432
   [junit4]   2> 2165473 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0]
   [junit4]   2> 2165473 WARN  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/lib
   [junit4]   2> 2165473 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
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> 2165474 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2165474 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2165475 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2165475 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 2165475 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2165475 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoresLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores
   [junit4]   2> 2165476 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: 
{absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/,
 name=collection1, schema=schema.xml, loadOnStartup=true, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1,
 configSetProperties=configsetprops.json, configSet=collection1, 
config=solrconfig.xml, dataDir=data/, transient=false}
   [junit4]   2> 2165476 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/
   [junit4]   2> 2165476 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 2165476 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 2165477 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2
   [junit4]   2> 2165477 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2165477 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2165477 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 2165477 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 2165477 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 2165477 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 2165501 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2165519 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 2165557 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2165563 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2165793 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2165799 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2165801 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2165805 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2165808 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2165810 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2165810 WARN  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2165810 WARN  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2165810 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2165810 WARN  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2165810 WARN  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2165812 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 2165812 INFO  (coreLoadExecutor-4508-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1
   [junit4]   2> 2165812 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2165812 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1],
 dataDir=[null]
   [junit4]   2> 2165812 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19efd160
   [junit4]   2> 2165813 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/data
   [junit4]   2> 2165813 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/data/index/
   [junit4]   2> 2165813 WARN  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2165814 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 2165814 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=1.17578125, 
floorSegmentMB=1.2587890625, forceMergeDeletesPctAllowed=25.58422740531904, 
segmentsPerTier=5.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.532752684939979
   [junit4]   2> 2165816 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-004
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_1,generation=1}
   [junit4]   2> 2165816 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2165816 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-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/data/
   [junit4]   2> 2165817 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 2165821 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 2165821 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2165821 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2165821 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 2165821 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2165822 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2165822 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2165822 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2165822 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2165822 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2165822 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 2165822 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 2165824 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2165826 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2165827 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2165828 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2165833 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 2165835 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/schema,defaults,/get,/config,lazy,mltrh,/replication,tvrh,/debug/dump,/admin/segments,spellCheckCompRH,/update/json,/admin/properties,spellCheckCompRH_Direct,/admin/file,/admin/logging,/terms,/update/json/docs,/search-facet-def,/admin/threads,/search-facet-invariants,/admin/ping,/mlt,standard,/update,/update/csv,/admin/system,mock,spellCheckWithWordbreak,/admin/mbeans,spellCheckWithWordbreak_Direct,/admin/plugins,spellCheckCompRH1,dismax,/admin/luke
   [junit4]   2> 2165837 WARN  (coreLoadExecutor-4508-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> 2165837 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2165838 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 2165838 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 2165838 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 2165839 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-004
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_1,generation=1}
   [junit4]   2> 2165839 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2165839 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@55bb101b[collection1] 
main
   [junit4]   2> 2165840 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 2165840 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 2165840 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2165840 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2165848 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2165878 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2165878 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2165879 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2165879 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2165879 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2165879 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2165880 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2165880 INFO  (coreLoadExecutor-4508-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2165880 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2165880 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2165881 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2165882 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@55bb101b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2165978 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 2165981 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2165981 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {deleteByQuery=*:*} 0 0
   [junit4]   2> 2165983 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2165983 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {deleteByQuery=*:*} 0 0
   [junit4]   2> 2165988 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[1]} 0 2
   [junit4]   2> 2165992 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[1]} 0 2
   [junit4]   2> 2165995 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[2]} 0 1
   [junit4]   2> 2165998 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[2]} 0 1
   [junit4]   2> 2166001 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[3]} 0 0
   [junit4]   2> 2166004 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[3]} 0 0
   [junit4]   2> 2166007 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[4]} 0 1
   [junit4]   2> 2166010 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[4]} 0 0
   [junit4]   2> 2166013 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[5]} 0 0
   [junit4]   2> 2166015 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[5]} 0 0
   [junit4]   2> 2166018 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[6]} 0 0
   [junit4]   2> 2166021 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[6]} 0 0
   [junit4]   2> 2166023 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[7]} 0 0
   [junit4]   2> 2166026 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[7]} 0 0
   [junit4]   2> 2166028 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[8]} 0 0
   [junit4]   2> 2166031 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[8]} 0 0
   [junit4]   2> 2166034 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[9]} 0 0
   [junit4]   2> 2166036 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[9]} 0 0
   [junit4]   2> 2166038 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2166046 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-002
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_1,generation=1}
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-002
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_2,generation=2}
   [junit4]   2> 2166047 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 2166050 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@56fc74e2[collection1] main
   [junit4]   2> 2166050 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2166052 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@56fc74e2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C9)))}
   [junit4]   2> 2166052 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={softCommit=false&commit=true&version=2&wt=javabin&waitSearcher=true} 
{commit=} 0 13
   [junit4]   2> 2166054 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2166062 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-004
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_1,generation=1}
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-004
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_2,generation=2}
   [junit4]   2> 2166062 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 2166065 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@1b16b40d[collection1] main
   [junit4]   2> 2166065 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2166066 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1b16b40d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C9)))}
   [junit4]   2> 2166067 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={softCommit=false&commit=true&version=2&wt=javabin&waitSearcher=true} 
{commit=} 0 12
   [junit4]   2> 2166070 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[10]} 0 1
   [junit4]   2> 2166074 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[10]} 0 1
   [junit4]   2> 2166077 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[11]} 0 0
   [junit4]   2> 2166079 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[11]} 0 0
   [junit4]   2> 2166082 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[12]} 0 0
   [junit4]   2> 2166085 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[12]} 0 0
   [junit4]   2> 2166087 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[13]} 0 0
   [junit4]   2> 2166090 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[13]} 0 0
   [junit4]   2> 2166093 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[1001]} 0 0
   [junit4]   2> 2166096 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[1001]} 0 1
   [junit4]   2> 2166098 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[14]} 0 0
   [junit4]   2> 2166100 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[14]} 0 0
   [junit4]   2> 2166103 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[15]} 0 0
   [junit4]   2> 2166106 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[15]} 0 0
   [junit4]   2> 2166108 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[16]} 0 0
   [junit4]   2> 2166110 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[16]} 0 0
   [junit4]   2> 2166114 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[17]} 0 1
   [junit4]   2> 2166118 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[17]} 0 1
   [junit4]   2> 2166121 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[100]} 0 0
   [junit4]   2> 2166123 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[100]} 0 0
   [junit4]   2> 2166126 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[101]} 0 0
   [junit4]   2> 2166129 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[101]} 0 0
   [junit4]   2> 2166132 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[102]} 0 0
   [junit4]   2> 2166134 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[102]} 0 0
   [junit4]   2> 2166137 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[103]} 0 0
   [junit4]   2> 2166139 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[103]} 0 0
   [junit4]   2> 2166141 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[104]} 0 0
   [junit4]   2> 2166144 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[104]} 0 0
   [junit4]   2> 2166148 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[105]} 0 1
   [junit4]   2> 2166150 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[105]} 0 0
   [junit4]   2> 2166153 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[106]} 0 0
   [junit4]   2> 2166155 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[106]} 0 0
   [junit4]   2> 2166158 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[107]} 0 0
   [junit4]   2> 2166160 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[107]} 0 0
   [junit4]   2> 2166163 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[108]} 0 0
   [junit4]   2> 2166165 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[108]} 0 0
   [junit4]   2> 2166168 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[109]} 0 0
   [junit4]   2> 2166172 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[109]} 0 2
   [junit4]   2> 2166175 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[110]} 0 0
   [junit4]   2> 2166178 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[110]} 0 0
   [junit4]   2> 2166180 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[111]} 0 0
   [junit4]   2> 2166183 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[111]} 0 0
   [junit4]   2> 2166185 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[112]} 0 0
   [junit4]   2> 2166187 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[112]} 0 0
   [junit4]   2> 2166190 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[113]} 0 0
   [junit4]   2> 2166192 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[113]} 0 0
   [junit4]   2> 2166195 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[114]} 0 0
   [junit4]   2> 2166197 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[114]} 0 0
   [junit4]   2> 2166199 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[115]} 0 0
   [junit4]   2> 2166202 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[115]} 0 0
   [junit4]   2> 2166204 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[116]} 0 0
   [junit4]   2> 2166207 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[116]} 0 0
   [junit4]   2> 2166209 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[117]} 0 0
   [junit4]   2> 2166211 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[117]} 0 0
   [junit4]   2> 2166214 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[118]} 0 0
   [junit4]   2> 2166216 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[118]} 0 0
   [junit4]   2> 2166219 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[119]} 0 0
   [junit4]   2> 2166221 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[119]} 0 0
   [junit4]   2> 2166223 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[120]} 0 0
   [junit4]   2> 2166226 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[120]} 0 0
   [junit4]   2> 2166228 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[121]} 0 0
   [junit4]   2> 2166231 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[121]} 0 0
   [junit4]   2> 2166233 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[122]} 0 0
   [junit4]   2> 2166235 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[122]} 0 0
   [junit4]   2> 2166238 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[123]} 0 0
   [junit4]   2> 2166240 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[123]} 0 0
   [junit4]   2> 2166243 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[124]} 0 0
   [junit4]   2> 2166245 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[124]} 0 0
   [junit4]   2> 2166248 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[125]} 0 0
   [junit4]   2> 2166250 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[125]} 0 0
   [junit4]   2> 2166253 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[126]} 0 0
   [junit4]   2> 2166255 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[126]} 0 0
   [junit4]   2> 2166258 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[127]} 0 0
   [junit4]   2> 2166260 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[127]} 0 0
   [junit4]   2> 2166262 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[128]} 0 0
   [junit4]   2> 2166265 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[128]} 0 0
   [junit4]   2> 2166267 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[129]} 0 0
   [junit4]   2> 2166269 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[129]} 0 0
   [junit4]   2> 2166272 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[130]} 0 0
   [junit4]   2> 2166274 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[130]} 0 0
   [junit4]   2> 2166277 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[131]} 0 0
   [junit4]   2> 2166279 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[131]} 0 0
   [junit4]   2> 2166281 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[132]} 0 0
   [junit4]   2> 2166284 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[132]} 0 0
   [junit4]   2> 2166286 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[133]} 0 0
   [junit4]   2> 2166289 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[133]} 0 0
   [junit4]   2> 2166291 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[134]} 0 0
   [junit4]   2> 2166293 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[134]} 0 0
   [junit4]   2> 2166296 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[135]} 0 0
   [junit4]   2> 2166298 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[135]} 0 0
   [junit4]   2> 2166300 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[136]} 0 0
   [junit4]   2> 2166303 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[136]} 0 0
   [junit4]   2> 2166305 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[137]} 0 0
   [junit4]   2> 2166308 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[137]} 0 0
   [junit4]   2> 2166310 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[138]} 0 0
   [junit4]   2> 2166313 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[138]} 0 0
   [junit4]   2> 2166317 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[139]} 0 0
   [junit4]   2> 2166320 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[139]} 0 0
   [junit4]   2> 2166322 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[140]} 0 0
   [junit4]   2> 2166325 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[140]} 0 0
   [junit4]   2> 2166327 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[141]} 0 0
   [junit4]   2> 2166330 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[141]} 0 0
   [junit4]   2> 2166333 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[142]} 0 0
   [junit4]   2> 2166335 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[142]} 0 0
   [junit4]   2> 2166338 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[143]} 0 0
   [junit4]   2> 2166340 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[143]} 0 0
   [junit4]   2> 2166342 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[144]} 0 0
   [junit4]   2> 2166345 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[144]} 0 0
   [junit4]   2> 2166347 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[145]} 0 0
   [junit4]   2> 2166350 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[145]} 0 0
   [junit4]   2> 2166352 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[146]} 0 0
   [junit4]   2> 2166355 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[146]} 0 0
   [junit4]   2> 2166358 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[147]} 0 0
   [junit4]   2> 2166361 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[147]} 0 0
   [junit4]   2> 2166364 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[148]} 0 0
   [junit4]   2> 2166367 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[148]} 0 0
   [junit4]   2> 2166369 INFO  (qtp738645470-14095) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[149]} 0 0
   [junit4]   2> 2166372 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={version=2&wt=javabin} {add=[149]} 0 0
   [junit4]   2> 2166374 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2166393 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-002
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_2,generation=2}
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-002
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_3,generation=3}
   [junit4]   2> 2166393 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 3
   [junit4]   2> 2166394 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@7c1a7d46[collection1] main
   [junit4]   2> 2166394 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2166396 INFO  
(searcherExecutor-4499-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7c1a7d46[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C9)
 Uninverting(_1(5.5.0):C59)))}
   [junit4]   2> 2166396 INFO  (qtp738645470-14091) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={softCommit=false&commit=true&version=2&wt=javabin&waitSearcher=true} 
{commit=} 0 21
   [junit4]   2> 2166398 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2166414 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-004
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_2,generation=2}
   [junit4]   2>        
commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/index-SimpleFSDirectory-004
 
lockFactory=org.apache.lucene.store.NativeFSLockFactory@29f68ad9),segFN=segments_3,generation=3}
   [junit4]   2> 2166414 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 3
   [junit4]   2> 2166415 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@77abb39e[collection1] main
   [junit4]   2> 2166415 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2166416 INFO  
(searcherExecutor-4509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@77abb39e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C9)
 Uninverting(_1(5.5.0):C59)))}
   [junit4]   2> 2166417 INFO  (qtp108976334-14109) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update 
params={softCommit=false&commit=true&version=2&wt=javabin&waitSearcher=true} 
{commit=} 0 18
   [junit4]   2> 2166419 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.c.SolrCore Ignoring exception matching parameter cannot be negative
   [junit4]   2> 2166419 INFO  (qtp738645470-14092) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={start=-1&q=*&version=2&wt=javabin&distrib=false} status=400 QTime=0 
   [junit4]   2> 2166422 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.c.SolrCore Ignoring exception matching parameter cannot be negative
   [junit4]   2> 2166422 INFO  (qtp738645470-14093) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={start=0&q=*&version=2&wt=javabin&distrib=false&rows=-1} status=400 
QTime=0 
   [junit4]   2> 2166425 INFO  (qtp738645470-14094) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={sort=n_ti1+desc&q=*:*&version=2&wt=javabin&distrib=false} hits=68 
status=0 QTime=1 
   [junit4]   2> 2166509 INFO  (qtp108976334-14111) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={isShard=true&q=*:*&version=2&rows=10&distrib=false&shard.url=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&NOW=1448720185112&fl=id&start=0&shards.purpose=4&wt=javabin&fsv=true}
 hits=68 status=0 QTime=1 
   [junit4]   2> 2166514 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={NOW=1448720185112&ids=132,135,7,2,119,102,142,145,147,105&isShard=true&shards.purpose=64&q=*:*&version=2&wt=javabin&shard.url=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/&distrib=false}
 status=0 QTime=0 
   [junit4]   2> 2166518 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={sort=n_ti1+desc&q=*:*&version=2&wt=javabin&shards=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/}
 hits=68 status=0 QTime=88 
   [junit4]   2> 2166519 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2166523 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={isShard=true&q=*:*&version=2&rows=10&distrib=false&shard.url=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&NOW=1448720185203&fl=id&start=0&shards.purpose=4&wt=javabin&fsv=true}
 hits=68 status=0 QTime=0 
   [junit4]   2> 2166526 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={NOW=1448720185203&ids=132,135,7,2,119,102,142,145,147,105&isShard=true&shards.purpose=64&q=*:*&version=2&wt=javabin&shard.url=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/&distrib=false}
 status=0 QTime=0 
   [junit4]   2> 2166527 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={sort=n_ti1+desc&q=*:*&version=2&wt=javabin&shards=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/}
 hits=68 status=0 QTime=6 
   [junit4]   2> 2166704 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={isShard=true&q=*:*&version=2&rows=10&distrib=false&shard.url=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&NOW=1448720185383&fl=id&start=0&shards.purpose=4&wt=javabin&fsv=true}
 hits=68 status=0 QTime=0 
   [junit4]   2> 2166707 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={NOW=1448720185383&ids=132,135,7,2,119,102,142,145,147,105&isShard=true&shards.purpose=64&q=*:*&version=2&wt=javabin&shard.url=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/&distrib=false}
 status=0 QTime=0 
   [junit4]   2> 2166707 INFO  (qtp108976334-14112) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={sort=n_ti1+desc&q=*:*&version=2&wt=javabin&shards=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/}
 hits=68 status=0 QTime=6 
   [junit4]   2> 2166776 INFO  (qtp108976334-14110) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={isShard=true&q=*:*&version=2&rows=10&distrib=false&shard.url=[ff01::083]:33332/|http://127.0.0.1:55549//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&NOW=1448720185203&fl=id&start=0&shards.purpose=4&wt=javabin&fsv=true}
 hits=68 status=0 QTime=0 
   [junit4]   2> 2166780 INFO  (qtp108976334-14113) [    x:collection1] 
o.a.s.c.S.Request [collection1] webapp= path=/select 
params={NOW=1448720185203&ids=132,135,7,2,119,102,142,145,147,105&isShard=true&shards.purpose=64&q=*:*&version=2&wt=javab

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

s/collection1/data/index;done=false>>]
   [junit4]   2> 2268491 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 2268493 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2bf26ef8{/,null,UNAVAILABLE}
   [junit4]   2> 2268500 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.e.j.s.ServerConnector Stopped ServerConnector@1617e798{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 2268500 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1743729531
   [junit4]   2> 2268500 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4681538b
   [junit4]   2> 2268501 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=2,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=34,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
   [junit4]   2> 2268501 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 2268501 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing 
IndexWriter
   [junit4]   2> 2268501 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 2268502 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 2268516 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories 
currently being tracked
   [junit4]   2> 2268516 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard1/cores/collection1/data/index
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard1/cores/collection1/data/index;done=false>>]
   [junit4]   2> 2268516 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard1/cores/collection1/data/index
   [junit4]   2> 2268516 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard1/cores/collection1/data
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard1/cores/collection1/data;done=false>>]
   [junit4]   2> 2268516 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001/tempDir-001/shard1/cores/collection1/data
   [junit4]   2> 2268517 INFO  
(TEST-TestDistributedSearch.test-seed#[DA3F7411FFE8837F]) [    x:collection1] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5307e9ac{/,null,UNAVAILABLE}
   [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=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=DA3F7411FFE8837F -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=es_MX -Dtests.timezone=America/Maceio -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR    104s J2 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.NullPointerException
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([DA3F7411FFE8837F:526B4BCB5114EE87]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:568)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:986)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2268521 INFO  
(SUITE-TestDistributedSearch-seed#[DA3F7411FFE8837F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_DA3F7411FFE8837F-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=es_MX, 
timezone=America/Maceio
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.7.0_80 (64-bit)/cpus=4,threads=1,free=205244096,total=487587840
   [junit4]   2> NOTE: All tests run in this JVM: [QueryParsingTest, 
TestInfoStreamLogging, TestLeaderElectionZkExpiry, TestJsonRequest, 
TestReloadDeadlock, TestOrdValues, TestManagedSchemaDynamicFieldResource, 
TestSolrDeletionPolicy1, ScriptEngineTest, ShowFileRequestHandlerTest, 
TestReversedWildcardFilterFactory, TestZkChroot, TestSuggestSpellingConverter, 
UpdateParamsTest, TestRandomRequestDistribution, TestIndexSearcher, 
TestJmxMonitoredMap, TestMiniSolrCloudCluster, TestGroupingSearch, 
FieldAnalysisRequestHandlerTest, MultiThreadedOCPTest, 
TestComplexPhraseQParserPlugin, SolrXmlInZkTest, LeaderElectionTest, TestSolrJ, 
TestIntervalFaceting, DistributedFacetPivotSmallTest, DirectSolrConnectionTest, 
TestSchemaVersionResource, BlockCacheTest, MergeStrategyTest, ForceLeaderTest, 
DistributedQueryComponentCustomSortTest, HardAutoCommitTest, TestRestoreCore, 
SliceStateTest, AssignTest, DocExpirationUpdateProcessorFactoryTest, 
SparseHLLTest, RegexBoostProcessorTest, PluginInfoTest, 
CollectionStateFormat2Test, TestSolrIndexConfig, 
TestRandomCollapseQParserPlugin, PrimitiveFieldTypeTest, SuggesterTest, 
TestStressRecovery, TestFileDictionaryLookup, RemoteQueryErrorTest, 
DirectUpdateHandlerOptimizeTest, RequestHandlersTest, BlockDirectoryTest, 
QueryResultKeyTest, SuggesterTSTTest, BasicDistributedZk2Test, 
CoreAdminRequestStatusTest, TestConfigOverlay, TestConfigSetsAPI, 
TestTrackingShardHandlerFactory, OverseerTest, 
CollectionsAPIAsyncDistributedZkTest, OverseerStatusTest, TestScoreJoinQPScore, 
CurrencyFieldXmlFileTest, SharedFSAutoReplicaFailoverUtilsTest, 
AliasIntegrationTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
TestAuthenticationFramework, UnloadDistributedZkTest, CustomCollectionTest, 
HighlighterConfigTest, TestIndexingPerformance, TestReplicaProperties, 
TestMiniSolrCloudClusterBase, TestHashQParserPlugin, HdfsSyncSliceTest, 
FieldMutatingUpdateProcessorTest, TestCloudManagedSchemaConcurrent, 
TestBlendedInfixSuggestions, AddSchemaFieldsUpdateProcessorFactoryTest, 
DirectUpdateHandlerTest, TestCloudInspectUtil, TestCharFilters, 
AtomicUpdatesTest, TestCoreDiscovery, DateRangeFieldTest, TestNoOpRegenerator, 
TestExactSharedStatsCache, TestExtendedDismaxParser, ClusterStateUpdateTest, 
TestRandomDVFaceting, TestDistributedSearch]
   [junit4] Completed [295/551] on J2 in 104.16s, 1 test, 1 error <<< FAILURES!

[...truncated 832 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:792: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:729: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:59: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:526:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1452:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1006:
 There were test failures: 551 suites (7 ignored), 2177 tests, 1 error, 32 
ignored (18 assumptions) [seed: DA3F7411FFE8837F]

Total time: 183 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


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

Reply via email to