[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_102) - Build # 17512 - Still Unstable!

[Policeman Jenkins Server]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17512/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseParallelGC

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

Error Message:
Expected to find shardAddress in the up shard info

Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info
        at 
__randomizedtesting.SeedInfo.seed([EF8A6F5C131826AF:67DE5086BDE44B57]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1172)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1113)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:973)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        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:1011)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        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:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        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:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        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 11414 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/init-core-data-001
   [junit4]   2> 974266 INFO  
(SUITE-TestDistributedSearch-seed#[EF8A6F5C131826AF]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9061)
   [junit4]   2> 974266 INFO  
(SUITE-TestDistributedSearch-seed#[EF8A6F5C131826AF]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /q_k/wk
   [junit4]   2> 974319 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1
   [junit4]   2> 974320 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 974321 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2549f1c{/q_k/wk,null,AVAILABLE}
   [junit4]   2> 974335 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@23938c2e{HTTP/1.1,[http/1.1]}{127.0.0.1:43863}
   [junit4]   2> 974335 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] o.e.j.s.Server 
Started @975962ms
   [junit4]   2> 974335 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/q_k/wk, 
hostPort=43863, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores}
   [junit4]   2> 974335 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 974335 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control'
   [junit4]   2> 974335 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 974335 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 974335 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/solr.xml
   [junit4]   2> 974339 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores
   [junit4]   2> 974339 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 867573316
   [junit4]   2> 974339 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control]
   [junit4]   2> 974339 WARN  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/lib
 to classpath: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/lib
   [junit4]   2> 974339 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
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,
   [junit4]   2> 974340 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
   [junit4]   2> 974340 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 974340 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 974340 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 974340 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 974341 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores
   [junit4]   2> 974341 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, 
configSetProperties=configsetprops.json, transient=false, dataDir=data/, 
configSet=collection1}
   [junit4]   2> 974341 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1
   [junit4]   2> 974341 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 974341 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 974341 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 974342 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 974342 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 974342 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 974342 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 974342 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 974354 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 974359 WARN  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 974360 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 974373 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 974376 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 974431 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 974434 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 974435 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 974437 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 974437 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 974438 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 974438 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 974439 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 974439 INFO  (coreLoadExecutor-3602-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 974439 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 974439 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 974439 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3aa1ec3a
   [junit4]   2> 974440 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1/data/snapshot_metadata
   [junit4]   2> 974440 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot 
metadata file...
   [junit4]   2> 974440 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1/data
   [junit4]   2> 974440 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1/data/index/
   [junit4]   2> 974440 WARN  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 974440 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1/data/index
   [junit4]   2> 974440 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.353627318784837]
   [junit4]   2> 974440 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3addb2c5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30b4f489),segFN=segments_1,generation=1}
   [junit4]   2> 974440 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 974441 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1/data/
   [junit4]   2> 974441 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.DirectoryFactory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/control/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 974443 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 974443 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 974443 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 974443 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 974444 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 974444 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 974444 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 974444 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 974444 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 974444 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 974444 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 974444 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 974445 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 974445 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 974446 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 974447 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 974448 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 974449 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 974449 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 974450 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 974450 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 974450 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 974450 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 974450 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=1.39453125, 
floorSegmentMB=0.861328125, forceMergeDeletesPctAllowed=0.4512402453051201, 
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5496901700256965
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3addb2c5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30b4f489),segFN=segments_1,generation=1}
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5114dddb[collection1] main]
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 974472 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 974597 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 974597 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 974597 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 974597 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 974597 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 974597 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 974597 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 974597 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 974597 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 974597 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1542196302403600384
   [junit4]   2> 974597 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 974597 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 974597 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 974598 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 974598 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5114dddb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974599 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with 
highest version 1542196302403600384
   [junit4]   2> 974599 INFO  (coreLoadExecutor-3602-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 974897 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 974897 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 974898 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@49ee1a69{/q_k/wk,null,AVAILABLE}
   [junit4]   2> 974899 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@6ae44bd7{HTTP/1.1,[http/1.1]}{127.0.0.1:46169}
   [junit4]   2> 974899 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] o.e.j.s.Server 
Started @976527ms
   [junit4]   2> 974899 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/q_k/wk, 
hostPort=46169, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores}
   [junit4]   2> 974899 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 974899 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0'
   [junit4]   2> 974900 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 974900 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 974900 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 974903 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores
   [junit4]   2> 974903 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 2009871305
   [junit4]   2> 974903 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0]
   [junit4]   2> 974903 WARN  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/lib
 to classpath: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/lib
   [junit4]   2> 974903 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
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,
   [junit4]   2> 974904 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
   [junit4]   2> 974904 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 974904 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 974905 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 974905 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 974905 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores
   [junit4]   2> 974905 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, 
configSetProperties=configsetprops.json, transient=false, dataDir=data/, 
configSet=collection1}
   [junit4]   2> 974905 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 974905 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 974905 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 974906 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1
   [junit4]   2> 974906 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 974906 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 974906 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 974906 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 974906 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 974925 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 974930 WARN  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 974931 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 974944 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 974948 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 975006 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 975010 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 975010 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 975012 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 975014 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 975014 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 975015 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3aa1ec3a
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1/data/snapshot_metadata
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot 
metadata file...
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1/data
   [junit4]   2> 975016 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1/data/index/
   [junit4]   2> 975016 WARN  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 975017 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 975017 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.353627318784837]
   [junit4]   2> 975017 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@765922f1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41d0702a),segFN=segments_1,generation=1}
   [junit4]   2> 975017 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 975017 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1/data/
   [junit4]   2> 975018 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.DirectoryFactory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard0/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 975021 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 975021 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 975021 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 975021 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 975022 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 975022 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 975022 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 975022 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 975022 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 975022 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 975022 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 975022 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 975023 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 975024 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 975024 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 975024 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 975027 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 975028 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/graph,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 975028 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 975028 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 975028 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 975029 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 975029 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 975029 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=1.39453125, 
floorSegmentMB=0.861328125, forceMergeDeletesPctAllowed=0.4512402453051201, 
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5496901700256965
   [junit4]   2> 975029 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@765922f1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41d0702a),segFN=segments_1,generation=1}
   [junit4]   2> 975029 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 975029 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4567998c[collection1] main]
   [junit4]   2> 975029 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 975029 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 975030 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 975030 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 975050 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 975138 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 975138 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 975138 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 975138 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 975138 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 975139 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 975139 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 975139 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 975139 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1542196302971928576
   [junit4]   2> 975139 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 975139 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 975139 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 975139 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 975139 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 975140 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4567998c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975140 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with 
highest version 1542196302971928576
   [junit4]   2> 975140 INFO  (coreLoadExecutor-3613-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 975407 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 975409 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1542196303253995520)} 0 0
   [junit4]   2> 975411 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1542196303256092672)} 0 0
   [junit4]   2> 975412 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[1 (1542196303257141248)]} 0 0
   [junit4]   2> 975413 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[1 (1542196303258189824)]} 0 0
   [junit4]   2> 975413 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[2 (1542196303259238400)]} 0 0
   [junit4]   2> 975414 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[2 (1542196303260286976)]} 0 0
   [junit4]   2> 975415 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[3 (1542196303261335552)]} 0 0
   [junit4]   2> 975415 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[3 (1542196303261335552)]} 0 0
   [junit4]   2> 975416 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[4 (1542196303262384128)]} 0 0
   [junit4]   2> 975416 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[4 (1542196303262384128)]} 0 0
   [junit4]   2> 975417 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[5 (1542196303263432704)]} 0 0
   [junit4]   2> 975417 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[5 (1542196303263432704)]} 0 0
   [junit4]   2> 975418 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[6 (1542196303263432705)]} 0 0
   [junit4]   2> 975418 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[6 (1542196303264481280)]} 0 0
   [junit4]   2> 975419 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[7 (1542196303264481280)]} 0 0
   [junit4]   2> 975419 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[7 (1542196303265529856)]} 0 0
   [junit4]   2> 975420 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[8 (1542196303265529856)]} 0 0
   [junit4]   2> 975420 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[8 (1542196303266578432)]} 0 0
   [junit4]   2> 975420 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[9 (1542196303266578432)]} 0 0
   [junit4]   2> 975421 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[9 (1542196303267627008)]} 0 0
   [junit4]   2> 975421 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975427 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3addb2c5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30b4f489),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3addb2c5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30b4f489),segFN=segments_2,generation=2}
   [junit4]   2> 975428 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 975429 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@52a80705[collection1] main]
   [junit4]   2> 975429 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 975430 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@52a80705[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
   [junit4]   2> 975430 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 9
   [junit4]   2> 975431 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975434 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@765922f1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41d0702a),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@765922f1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41d0702a),segFN=segments_2,generation=2}
   [junit4]   2> 975434 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 975435 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@164e9f35[collection1] main]
   [junit4]   2> 975436 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 975436 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@164e9f35[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
   [junit4]   2> 975436 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 975439 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[10 (1542196303284404224)]} 0 2
   [junit4]   2> 975440 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[10 (1542196303286501376)]} 0 0
   [junit4]   2> 975441 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[11 (1542196303287549952)]} 0 0
   [junit4]   2> 975441 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[11 (1542196303288598528)]} 0 0
   [junit4]   2> 975442 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[12 (1542196303288598528)]} 0 0
   [junit4]   2> 975442 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[12 (1542196303289647104)]} 0 0
   [junit4]   2> 975442 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[13 (1542196303289647104)]} 0 0
   [junit4]   2> 975443 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[13 (1542196303290695680)]} 0 0
   [junit4]   2> 975444 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[1001 (1542196303290695680)]} 0 0
   [junit4]   2> 975444 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[1001 (1542196303291744256)]} 0 0
   [junit4]   2> 975445 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[14 (1542196303291744256)]} 0 0
   [junit4]   2> 975445 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[14 (1542196303292792832)]} 0 0
   [junit4]   2> 975445 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[15 (1542196303292792832)]} 0 0
   [junit4]   2> 975446 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[15 (1542196303293841408)]} 0 0
   [junit4]   2> 975446 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[16 (1542196303293841408)]} 0 0
   [junit4]   2> 975447 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[16 (1542196303293841409)]} 0 0
   [junit4]   2> 975447 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[17 (1542196303294889984)]} 0 0
   [junit4]   2> 975448 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[17 (1542196303294889984)]} 0 0
   [junit4]   2> 975448 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[100 (1542196303295938560)]} 0 0
   [junit4]   2> 975448 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[100 (1542196303295938560)]} 0 0
   [junit4]   2> 975449 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[101 (1542196303296987136)]} 0 0
   [junit4]   2> 975449 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[101 (1542196303296987136)]} 0 0
   [junit4]   2> 975450 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[102 (1542196303296987137)]} 0 0
   [junit4]   2> 975451 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[102 (1542196303299084288)]} 0 0
   [junit4]   2> 975451 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[103 (1542196303299084288)]} 0 0
   [junit4]   2> 975452 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[103 (1542196303300132864)]} 0 0
   [junit4]   2> 975452 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[104 (1542196303300132864)]} 0 0
   [junit4]   2> 975453 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[104 (1542196303301181440)]} 0 0
   [junit4]   2> 975453 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[105 (1542196303301181440)]} 0 0
   [junit4]   2> 975453 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[105 (1542196303301181441)]} 0 0
   [junit4]   2> 975454 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[106 (1542196303302230016)]} 0 0
   [junit4]   2> 975454 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[106 (1542196303302230016)]} 0 0
   [junit4]   2> 975455 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[107 (1542196303302230017)]} 0 0
   [junit4]   2> 975455 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[107 (1542196303303278592)]} 0 0
   [junit4]   2> 975455 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[108 (1542196303303278592)]} 0 0
   [junit4]   2> 975456 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[108 (1542196303304327168)]} 0 0
   [junit4]   2> 975456 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[109 (1542196303304327168)]} 0 0
   [junit4]   2> 975456 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[109 (1542196303304327169)]} 0 0
   [junit4]   2> 975457 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[110 (1542196303305375744)]} 0 0
   [junit4]   2> 975457 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[110 (1542196303305375744)]} 0 0
   [junit4]   2> 975458 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[111 (1542196303305375745)]} 0 0
   [junit4]   2> 975458 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[111 (1542196303306424320)]} 0 0
   [junit4]   2> 975458 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[112 (1542196303306424320)]} 0 0
   [junit4]   2> 975459 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[112 (1542196303307472896)]} 0 0
   [junit4]   2> 975459 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[113 (1542196303307472896)]} 0 0
   [junit4]   2> 975459 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[113 (1542196303307472897)]} 0 0
   [junit4]   2> 975460 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[114 (1542196303308521472)]} 0 0
   [junit4]   2> 975460 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[114 (1542196303308521472)]} 0 0
   [junit4]   2> 975460 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[115 (1542196303308521473)]} 0 0
   [junit4]   2> 975461 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[115 (1542196303309570048)]} 0 0
   [junit4]   2> 975461 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[116 (1542196303309570048)]} 0 0
   [junit4]   2> 975462 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[116 (1542196303310618624)]} 0 0
   [junit4]   2> 975463 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[117 (1542196303311667200)]} 0 0
   [junit4]   2> 975463 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[117 (1542196303311667200)]} 0 0
   [junit4]   2> 975463 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[118 (1542196303311667201)]} 0 0
   [junit4]   2> 975464 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[118 (1542196303312715776)]} 0 0
   [junit4]   2> 975464 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[119 (1542196303312715776)]} 0 0
   [junit4]   2> 975464 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[119 (1542196303312715777)]} 0 0
   [junit4]   2> 975465 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[120 (1542196303313764352)]} 0 0
   [junit4]   2> 975465 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[120 (1542196303313764352)]} 0 0
   [junit4]   2> 975466 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[121 (1542196303313764353)]} 0 0
   [junit4]   2> 975466 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[121 (1542196303314812928)]} 0 0
   [junit4]   2> 975466 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[122 (1542196303314812928)]} 0 0
   [junit4]   2> 975467 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[122 (1542196303315861504)]} 0 0
   [junit4]   2> 975467 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[123 (1542196303315861504)]} 0 0
   [junit4]   2> 975467 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[123 (1542196303315861505)]} 0 0
   [junit4]   2> 975468 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[124 (1542196303316910080)]} 0 0
   [junit4]   2> 975468 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[124 (1542196303316910080)]} 0 0
   [junit4]   2> 975469 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[125 (1542196303316910081)]} 0 0
   [junit4]   2> 975469 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[125 (1542196303317958656)]} 0 0
   [junit4]   2> 975469 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[126 (1542196303317958656)]} 0 0
   [junit4]   2> 975470 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[126 (1542196303317958657)]} 0 0
   [junit4]   2> 975470 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[127 (1542196303319007232)]} 0 0
   [junit4]   2> 975470 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[127 (1542196303319007232)]} 0 0
   [junit4]   2> 975471 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[128 (1542196303319007233)]} 0 0
   [junit4]   2> 975471 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[128 (1542196303320055808)]} 0 0
   [junit4]   2> 975471 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[129 (1542196303320055808)]} 0 0
   [junit4]   2> 975472 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[129 (1542196303320055809)]} 0 0
   [junit4]   2> 975472 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[130 (1542196303321104384)]} 0 0
   [junit4]   2> 975472 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[130 (1542196303321104384)]} 0 0
   [junit4]   2> 975473 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[131 (1542196303321104385)]} 0 0
   [junit4]   2> 975473 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[131 (1542196303322152960)]} 0 0
   [junit4]   2> 975473 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[132 (1542196303322152960)]} 0 0
   [junit4]   2> 975474 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[132 (1542196303323201536)]} 0 0
   [junit4]   2> 975475 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[133 (1542196303324250112)]} 0 0
   [junit4]   2> 975475 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[133 (1542196303324250112)]} 0 0
   [junit4]   2> 975475 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[134 (1542196303324250113)]} 0 0
   [junit4]   2> 975476 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[134 (1542196303325298688)]} 0 0
   [junit4]   2> 975476 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[135 (1542196303325298688)]} 0 0
   [junit4]   2> 975476 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[135 (1542196303325298689)]} 0 0
   [junit4]   2> 975477 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[136 (1542196303326347264)]} 0 0
   [junit4]   2> 975477 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[136 (1542196303326347264)]} 0 0
   [junit4]   2> 975477 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[137 (1542196303326347265)]} 0 0
   [junit4]   2> 975478 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[137 (1542196303327395840)]} 0 0
   [junit4]   2> 975478 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[138 (1542196303327395840)]} 0 0
   [junit4]   2> 975478 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[138 (1542196303327395841)]} 0 0
   [junit4]   2> 975479 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[139 (1542196303328444416)]} 0 0
   [junit4]   2> 975479 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[139 (1542196303328444416)]} 0 0
   [junit4]   2> 975480 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[140 (1542196303328444417)]} 0 0
   [junit4]   2> 975480 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[140 (1542196303329492992)]} 0 0
   [junit4]   2> 975480 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[141 (1542196303329492992)]} 0 0
   [junit4]   2> 975481 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[141 (1542196303330541568)]} 0 0
   [junit4]   2> 975481 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[142 (1542196303330541568)]} 0 0
   [junit4]   2> 975481 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[142 (1542196303330541569)]} 0 0
   [junit4]   2> 975482 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[143 (1542196303331590144)]} 0 0
   [junit4]   2> 975482 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[143 (1542196303331590144)]} 0 0
   [junit4]   2> 975483 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[144 (1542196303331590145)]} 0 0
   [junit4]   2> 975483 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[144 (1542196303332638720)]} 0 0
   [junit4]   2> 975483 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[145 (1542196303332638720)]} 0 0
   [junit4]   2> 975484 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[145 (1542196303332638721)]} 0 0
   [junit4]   2> 975484 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[146 (1542196303333687296)]} 0 0
   [junit4]   2> 975484 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[146 (1542196303333687296)]} 0 0
   [junit4]   2> 975493 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[147 (1542196303343124480)]} 0 8
   [junit4]   2> 975494 INFO  (qtp1091550639-8730) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[147 (1542196303343124480)]} 0 0
   [junit4]   2> 975494 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[148 (1542196303344173056)]} 0 0
   [junit4]   2> 975495 INFO  (qtp1091550639-8735) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[148 (1542196303344173056)]} 0 0
   [junit4]   2> 975495 INFO  (qtp1311497648-8719) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[149 (1542196303345221632)]} 0 0
   [junit4]   2> 975495 INFO  (qtp1091550639-8736) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={wt=javabin&version=2}{add=[149 (1542196303345221632)]} 0 0
   [junit4]   2> 975496 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975508 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3addb2c5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30b4f489),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3addb2c5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30b4f489),segFN=segments_3,generation=3}
   [junit4]   2> 975508 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 975508 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7a80681d[collection1] main]
   [junit4]   2> 975509 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 975509 INFO  
(searcherExecutor-3603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7a80681d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)
 Uninverting(_1(7.0.0):C59)))}
   [junit4]   2> 975509 INFO  (qtp1311497648-8720) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 13
   [junit4]   2> 975511 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 975523 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@765922f1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41d0702a),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@765922f1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41d0702a),segFN=segments_3,generation=3}
   [junit4]   2> 975523 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 975523 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6b4a3040[collection1] main]
   [junit4]   2> 975523 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 975524 INFO  
(searcherExecutor-3614-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6b4a3040[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)
 Uninverting(_1(7.0.0):C59)))}
   [junit4]   2> 975524 INFO  (qtp1091550639-8737) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/q_k/wk path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 12
   [junit4]   2> 975525 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 975525 INFO  (qtp1311497648-8713) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/q_k/wk path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 975525 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 975525 INFO  (qtp1311497648-8718) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/q_k/wk path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 975526 INFO  (qtp1311497648-8719) [    x:collection1] o.a.s.c.S

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

 (coreCloseExecutor-3660-thread-1) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 993412 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 993418 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 3 
directories currently being tracked
   [junit4]   2> 993418 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data;done=false>>]
   [junit4]   2> 993418 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 993418 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data
   [junit4]   2> 993418 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data/index
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data/index;done=false>>]
   [junit4]   2> 993418 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 993418 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data/index
   [junit4]   2> 993419 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data/snapshot_metadata
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data/snapshot_metadata;done=false>>]
   [junit4]   2> 993419 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 993419 INFO  (coreCloseExecutor-3660-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001/tempDir-001/shard1/cores/collection1/data/snapshot_metadata
   [junit4]   2> 993419 INFO  
(TEST-TestDistributedSearch.test-seed#[EF8A6F5C131826AF]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5cb7bc12{/q_k/wk,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=EF8A6F5C131826AF -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=fr-FR -Dtests.timezone=Indian/Chagos -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 19.2s J1 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([EF8A6F5C131826AF:67DE5086BDE44B57]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1172)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1113)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:973)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 993422 INFO  
(SUITE-TestDistributedSearch-seed#[EF8A6F5C131826AF]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_EF8A6F5C131826AF-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{other_tl1=PostingsFormat(name=LuceneFixedGap), 
range_facet_l_dv=PostingsFormat(name=Memory doPackFST= false), 
SubjectTerms_mfacet=PostingsFormat(name=LuceneFixedGap), 
n_l1=PostingsFormat(name=LuceneFixedGap), a_n_tdt=PostingsFormat(name=Memory 
doPackFST= false), multiDefault=BlockTreeOrds(blocksize=128), 
intDefault=PostingsFormat(name=LuceneFixedGap), 
n_td1=BlockTreeOrds(blocksize=128), n_d1=PostingsFormat(name=LuceneFixedGap), 
n_f1=BlockTreeOrds(blocksize=128), 
range_facet_l=PostingsFormat(name=LuceneFixedGap), 
n_tl1=BlockTreeOrds(blocksize=128), oddField_s=FSTOrd50, 
n_tf1=PostingsFormat(name=LuceneFixedGap), 
lowerfilt1and2=BlockTreeOrds(blocksize=128), 
a_t=PostingsFormat(name=LuceneFixedGap), 
lowerfilt=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=Memory 
doPackFST= false), text=FSTOrd50, 
timestamp=PostingsFormat(name=LuceneFixedGap), severity=FSTOrd50, 
foo_sev_enum=PostingsFormat(name=Memory doPackFST= false), 
n_dt1=BlockTreeOrds(blocksize=128), a_i1=PostingsFormat(name=LuceneFixedGap), 
b_n_tdt=PostingsFormat(name=LuceneFixedGap), n_ti1=PostingsFormat(name=Memory 
doPackFST= false), rnd_b=PostingsFormat(name=Memory doPackFST= false), 
_version_=PostingsFormat(name=LuceneFixedGap), foo_b=FSTOrd50, 
n_tdt1=PostingsFormat(name=LuceneFixedGap), foo_d=PostingsFormat(name=Memory 
doPackFST= false), range_facet_i_dv=PostingsFormat(name=LuceneFixedGap), 
foo_f=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), 
foo_sev_enum=DocValuesFormat(name=Memory), 
range_facet_i_dv=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=2017, 
maxMBSortInHeap=7.5614985087477224, sim=ClassicSimilarity, locale=fr-FR, 
timezone=Indian/Chagos
   [junit4]   2> NOTE: Linux 4.4.0-31-generic amd64/Oracle Corporation 
1.8.0_102 (64-bit)/cpus=12,threads=1,free=262990504,total=510656512
   [junit4]   2> NOTE: All tests run in this JVM: 
[SpellPossibilityIteratorTest, DistribJoinFromCollectionTest, DOMUtilTest, 
TestCustomDocTransformer, FacetPivotSmallTest, FastVectorHighlighterTest, 
TestQueryUtils, SolrCmdDistributorTest, DocumentBuilderTest, TestRTimerTree, 
UpdateRequestProcessorFactoryTest, MBeansHandlerTest, 
TestSimpleTrackingShardHandler, TestExactSharedStatsCache, 
CloudExitableDirectoryReaderTest, TestPKIAuthenticationPlugin, 
NumericFieldsTest, TestHighFrequencyDictionaryFactory, 
UniqFieldsUpdateProcessorFactoryTest, TestRandomFlRTGCloud, ResponseHeaderTest, 
SolrInfoMBeanTest, DataDrivenBlockJoinTest, TestSolrConfigHandler, 
DocValuesTest, BigEndianAscendingWordDeserializerTest, SolrIndexConfigTest, 
MinimalSchemaTest, TestSimpleQParserPlugin, TestLockTree, 
MoreLikeThisHandlerTest, VMParamsZkACLAndCredentialsProvidersTest, 
TestDocumentBuilder, DeleteLastCustomShardedReplicaTest, 
AsyncMigrateRouteKeyTest, FileUtilsTest, TestCollationFieldDocValues, 
SuggesterFSTTest, ZkStateWriterTest, CreateCollectionCleanupTest, 
BlockJoinFacetRandomTest, ChaosMonkeySafeLeaderTest, TestCodecSupport, 
SpatialFilterTest, TestFieldCacheVsDocValues, SolrXmlInZkTest, 
SolrIndexSplitterTest, TestFastLRUCache, ZkSolrClientTest, 
HdfsChaosMonkeyNothingIsSafeTest, SortByFunctionTest, 
TestBlendedInfixSuggestions, IndexBasedSpellCheckerTest, RemoteQueryErrorTest, 
TestSchemaNameResource, OpenCloseCoreStressTest, MergeStrategyTest, 
ExplicitHLLTest, TestTrieFacet, TestStressVersions, TestAddFieldRealTimeGet, 
TestPartialUpdateDeduplication, TestJsonFacets, 
DistributedFacetPivotSmallAdvancedTest, TestReplicaProperties, 
TestSchemaVersionResource, TimeZoneUtilsTest, TestSolrCoreProperties, 
ClusterStateUpdateTest, DistributedQueryComponentCustomSortTest, 
ForceLeaderTest, TestFilteredDocIdSet, TestSolrDeletionPolicy2, MultiTermTest, 
TestRecoveryHdfs, AliasIntegrationTest, NotRequiredUniqueKeyTest, 
OverseerCollectionConfigSetProcessorTest, HighlighterMaxOffsetTest, 
TestNRTOpen, AnalyticsMergeStrategyTest, TestWordDelimiterFilterFactory, 
HighlighterTest, TestFieldResource, TestImplicitCoreProperties, 
BasicAuthIntegrationTest, TestRawResponseWriter, SpellCheckComponentTest, 
OverseerStatusTest, TestCloudManagedSchema, SimpleCollectionCreateDeleteTest, 
SecurityConfHandlerTest, CdcrReplicationDistributedZkTest, TestPivotHelperCode, 
HdfsTlogReplayBufferedWhileIndexingTest, 
TestTolerantUpdateProcessorRandomCloud, TermsComponentTest, 
TestDownShardTolerantSearch, SuggestComponentTest, CoreAdminRequestStatusTest, 
TestConfigSetsAPIZkFailure, BasicDistributedZkTest, 
CollectionsAPIDistributedZkTest, TestRandomFaceting, LeaderElectionTest, 
ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch]
   [junit4] Completed [266/627 (1!)] on J1 in 19.17s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 61206 lines...]


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