[JENKINS] Lucene-Solr-Tests-master - Build # 1197 - Still Failing

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1197/

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([A5D189AD312CE909:2D85B6779FD084F1]: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 10885 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/init-core-data-001
   [junit4]   2> 384349 INFO  
(SUITE-TestDistributedSearch-seed#[A5D189AD312CE909]-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> 384349 INFO  
(SUITE-TestDistributedSearch-seed#[A5D189AD312CE909]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /a_avk/
   [junit4]   2> 384484 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1
   [junit4]   2> 384489 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 384574 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7c4887d2{/a_avk,null,AVAILABLE}
   [junit4]   2> 384575 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@23661dd{HTTP/1.1,[http/1.1]}{127.0.0.1:56865}
   [junit4]   2> 384575 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] o.e.j.s.Server 
Started @388237ms
   [junit4]   2> 384575 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/a_avk, 
hostPort=56865, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores}
   [junit4]   2> 384575 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 384575 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control'
   [junit4]   2> 384575 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 384575 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 384576 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/solr.xml
   [junit4]   2> 384580 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores
   [junit4]   2> 384580 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1564279694
   [junit4]   2> 384580 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control]
   [junit4]   2> 384580 WARN  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/lib
   [junit4]   2> 384583 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
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> 384594 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
   [junit4]   2> 384596 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 384596 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 384597 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 384597 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 384597 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores
   [junit4]   2> 384598 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
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> 384598 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1
   [junit4]   2> 384598 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 384610 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 384799 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 384799 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 384799 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 384799 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 384613 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0
   [junit4]   2> 384801 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 384813 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 384818 WARN  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 384819 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 384832 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 384835 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 384928 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 384932 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 384933 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 384937 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 384938 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 384939 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 384939 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 384940 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 384940 INFO  (coreLoadExecutor-1535-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 384940 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 384940 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 384940 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4030bfde
   [junit4]   2> 384941 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1/data
   [junit4]   2> 384941 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1/data/index/
   [junit4]   2> 384941 WARN  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 384941 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1/data/index
   [junit4]   2> 384941 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.40755474490328614]
   [junit4]   2> 384942 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@334d6b23 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34a6d5fd),segFN=segments_1,generation=1}
   [junit4]   2> 384942 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 384963 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 384964 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 384965 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 384966 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 384966 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 384969 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 384970 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 384971 INFO  (coreLoadExecutor-1535-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> 384971 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 384974 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1/data/
   [junit4]   2> 384974 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/control/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 384986 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 384993 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 384993 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 384993 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 384994 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=10, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=44.6240234375, 
floorSegmentMB=2.16015625, forceMergeDeletesPctAllowed=8.971805981323635, 
segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.21777016460086465
   [junit4]   2> 384999 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@334d6b23 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34a6d5fd),segFN=segments_1,generation=1}
   [junit4]   2> 385000 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 385000 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4802b568[collection1] main]
   [junit4]   2> 385000 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 385000 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 385000 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 385000 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 385008 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 385036 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 385036 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 385037 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 385037 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 385037 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 385037 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 385037 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 385037 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 385037 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 385037 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1536424031446630400
   [junit4]   2> 385037 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 385037 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 385037 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 385038 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 385038 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4802b568[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 385039 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with 
highest version 1536424031446630400
   [junit4]   2> 385039 INFO  (coreLoadExecutor-1535-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 385468 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 385469 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 385479 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@72c2f9e2{/a_avk,null,AVAILABLE}
   [junit4]   2> 385479 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@12843554{HTTP/1.1,[http/1.1]}{127.0.0.1:46252}
   [junit4]   2> 385479 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] o.e.j.s.Server 
Started @389142ms
   [junit4]   2> 385480 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/a_avk, 
hostPort=46252, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores}
   [junit4]   2> 385480 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 385480 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0'
   [junit4]   2> 385480 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 385480 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 385489 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 385492 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores
   [junit4]   2> 385492 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 710224343
   [junit4]   2> 385492 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0]
   [junit4]   2> 385492 WARN  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/lib
   [junit4]   2> 385493 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
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> 385495 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
   [junit4]   2> 385498 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 385498 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 385499 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 385499 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 385499 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores
   [junit4]   2> 385499 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
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> 385499 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 385499 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 385501 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 385515 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 385515 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 385515 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 385515 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 385517 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0
   [junit4]   2> 385517 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 385550 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 385555 WARN  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 385556 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 385586 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 385592 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 385719 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 385724 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 385724 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 385727 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 385728 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 385728 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 385729 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 385739 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 385739 INFO  (coreLoadExecutor-1546-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 385739 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 385739 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 385739 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4030bfde
   [junit4]   2> 385740 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1/data
   [junit4]   2> 385740 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1/data/index/
   [junit4]   2> 385740 WARN  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 385740 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 385740 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.40755474490328614]
   [junit4]   2> 385743 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@47d0da6e 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f73b150),segFN=segments_1,generation=1}
   [junit4]   2> 385743 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 385758 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1/data/
   [junit4]   2> 385758 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard0/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 385760 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 385761 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 385762 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 385763 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 385763 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 385764 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 385767 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 385768 INFO  (coreLoadExecutor-1546-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> 385768 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 385778 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 385778 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 385778 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 385778 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 385779 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=10, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=44.6240234375, 
floorSegmentMB=2.16015625, forceMergeDeletesPctAllowed=8.971805981323635, 
segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.21777016460086465
   [junit4]   2> 385779 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@47d0da6e 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f73b150),segFN=segments_1,generation=1}
   [junit4]   2> 385779 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 385780 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@17a7e77d[collection1] main]
   [junit4]   2> 385780 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 385780 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 385780 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 385780 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 385786 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 385804 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 385804 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 385804 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 385804 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 385804 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 385805 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 385805 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 385805 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 385805 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 385805 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 385805 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1536424032251936768
   [junit4]   2> 385805 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 385805 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 385805 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 385806 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17a7e77d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 385806 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with 
highest version 1536424032251936768
   [junit4]   2> 385807 INFO  (coreLoadExecutor-1546-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 386026 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 386054 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1536424032504643584)} 0 7
   [junit4]   2> 386081 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1536424032539246592)} 0 2
   [junit4]   2> 386084 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[1 (1536424032542392320)]} 0 1
   [junit4]   2> 386093 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[1 (1536424032548683776)]} 0 4
   [junit4]   2> 386094 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[2 (1536424032554975232)]} 0 0
   [junit4]   2> 386095 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[2 (1536424032556023808)]} 0 0
   [junit4]   2> 386096 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[3 (1536424032557072384)]} 0 0
   [junit4]   2> 386100 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[3 (1536424032561266688)]} 0 0
   [junit4]   2> 386101 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[4 (1536424032562315264)]} 0 0
   [junit4]   2> 386102 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[4 (1536424032563363840)]} 0 0
   [junit4]   2> 386103 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[5 (1536424032564412416)]} 0 0
   [junit4]   2> 386104 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[5 (1536424032564412416)]} 0 0
   [junit4]   2> 386105 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[6 (1536424032565460992)]} 0 0
   [junit4]   2> 386105 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[6 (1536424032566509568)]} 0 0
   [junit4]   2> 386106 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[7 (1536424032567558144)]} 0 0
   [junit4]   2> 386107 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[7 (1536424032568606720)]} 0 0
   [junit4]   2> 386108 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[8 (1536424032568606720)]} 0 0
   [junit4]   2> 386280 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[8 (1536424032750010368)]} 0 0
   [junit4]   2> 386281 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[9 (1536424032751058944)]} 0 0
   [junit4]   2> 386282 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[9 (1536424032752107520)]} 0 0
   [junit4]   2> 386282 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 386311 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@334d6b23 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34a6d5fd),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@334d6b23 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34a6d5fd),segFN=segments_2,generation=2}
   [junit4]   2> 386312 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 386319 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@58b8bff3[collection1] main]
   [junit4]   2> 386319 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 386320 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@58b8bff3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
   [junit4]   2> 386320 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 37
   [junit4]   2> 386321 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 386341 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@47d0da6e 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f73b150),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@47d0da6e 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f73b150),segFN=segments_2,generation=2}
   [junit4]   2> 386341 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 386343 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@796b2b6a[collection1] main]
   [junit4]   2> 386343 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 386344 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@796b2b6a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
   [junit4]   2> 386344 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 23
   [junit4]   2> 386347 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[10 (1536424032818167808)]} 0 2
   [junit4]   2> 386349 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[10 (1536424032821313536)]} 0 1
   [junit4]   2> 386350 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[11 (1536424032823410688)]} 0 0
   [junit4]   2> 386351 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[11 (1536424032824459264)]} 0 0
   [junit4]   2> 386358 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[12 (1536424032831799296)]} 0 0
   [junit4]   2> 386359 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[12 (1536424032832847872)]} 0 0
   [junit4]   2> 386360 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[13 (1536424032832847872)]} 0 0
   [junit4]   2> 386360 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[13 (1536424032833896448)]} 0 0
   [junit4]   2> 386362 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[1001 (1536424032834945024)]} 0 0
   [junit4]   2> 386363 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[1001 (1536424032837042176)]} 0 0
   [junit4]   2> 386364 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[14 (1536424032838090752)]} 0 0
   [junit4]   2> 386365 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[14 (1536424032839139328)]} 0 0
   [junit4]   2> 386366 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[15 (1536424032839139328)]} 0 0
   [junit4]   2> 386366 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[15 (1536424032840187904)]} 0 0
   [junit4]   2> 386367 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[16 (1536424032841236480)]} 0 0
   [junit4]   2> 386368 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[16 (1536424032842285056)]} 0 0
   [junit4]   2> 386369 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[17 (1536424032843333632)]} 0 0
   [junit4]   2> 386370 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[17 (1536424032844382208)]} 0 0
   [junit4]   2> 386371 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[100 (1536424032844382208)]} 0 0
   [junit4]   2> 386371 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[100 (1536424032845430784)]} 0 0
   [junit4]   2> 386372 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[101 (1536424032846479360)]} 0 0
   [junit4]   2> 386373 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[101 (1536424032846479360)]} 0 0
   [junit4]   2> 386373 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[102 (1536424032847527936)]} 0 0
   [junit4]   2> 386374 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[102 (1536424032848576512)]} 0 0
   [junit4]   2> 386374 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[103 (1536424032848576512)]} 0 0
   [junit4]   2> 386375 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[103 (1536424032849625088)]} 0 0
   [junit4]   2> 386376 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[104 (1536424032850673664)]} 0 0
   [junit4]   2> 386376 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[104 (1536424032850673664)]} 0 0
   [junit4]   2> 386377 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[105 (1536424032851722240)]} 0 0
   [junit4]   2> 386378 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[105 (1536424032852770816)]} 0 0
   [junit4]   2> 386379 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[106 (1536424032853819392)]} 0 0
   [junit4]   2> 386380 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[106 (1536424032854867968)]} 0 0
   [junit4]   2> 386381 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[107 (1536424032855916544)]} 0 0
   [junit4]   2> 386381 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[107 (1536424032855916544)]} 0 0
   [junit4]   2> 386382 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[108 (1536424032856965120)]} 0 0
   [junit4]   2> 386383 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[108 (1536424032856965120)]} 0 0
   [junit4]   2> 386383 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[109 (1536424032858013696)]} 0 0
   [junit4]   2> 386384 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[109 (1536424032859062272)]} 0 0
   [junit4]   2> 386385 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[110 (1536424032859062272)]} 0 0
   [junit4]   2> 386385 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[110 (1536424032860110848)]} 0 0
   [junit4]   2> 386386 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[111 (1536424032861159424)]} 0 0
   [junit4]   2> 386387 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[111 (1536424032861159424)]} 0 0
   [junit4]   2> 386387 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[112 (1536424032862208000)]} 0 0
   [junit4]   2> 386388 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[112 (1536424032863256576)]} 0 0
   [junit4]   2> 386389 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[113 (1536424032863256576)]} 0 0
   [junit4]   2> 386389 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[113 (1536424032864305152)]} 0 0
   [junit4]   2> 386390 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[114 (1536424032865353728)]} 0 0
   [junit4]   2> 386390 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[114 (1536424032865353728)]} 0 0
   [junit4]   2> 386391 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[115 (1536424032866402304)]} 0 0
   [junit4]   2> 386392 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[115 (1536424032866402304)]} 0 0
   [junit4]   2> 386392 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[116 (1536424032867450880)]} 0 0
   [junit4]   2> 386393 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[116 (1536424032868499456)]} 0 0
   [junit4]   2> 386394 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[117 (1536424032868499456)]} 0 0
   [junit4]   2> 386394 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[117 (1536424032869548032)]} 0 0
   [junit4]   2> 386395 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[118 (1536424032870596608)]} 0 0
   [junit4]   2> 386395 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[118 (1536424032870596608)]} 0 0
   [junit4]   2> 386398 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[119 (1536424032873742336)]} 0 0
   [junit4]   2> 386410 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[119 (1536424032886325248)]} 0 0
   [junit4]   2> 386411 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[120 (1536424032887373824)]} 0 0
   [junit4]   2> 386412 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[120 (1536424032887373824)]} 0 0
   [junit4]   2> 386412 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[121 (1536424032888422400)]} 0 0
   [junit4]   2> 386421 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[121 (1536424032896811008)]} 0 0
   [junit4]   2> 386421 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[122 (1536424032897859584)]} 0 0
   [junit4]   2> 386422 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[122 (1536424032898908160)]} 0 0
   [junit4]   2> 386423 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[123 (1536424032898908160)]} 0 0
   [junit4]   2> 386424 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[123 (1536424032899956736)]} 0 0
   [junit4]   2> 386424 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[124 (1536424032901005312)]} 0 0
   [junit4]   2> 386425 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[124 (1536424032902053888)]} 0 0
   [junit4]   2> 386426 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[125 (1536424032902053888)]} 0 0
   [junit4]   2> 386426 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[125 (1536424032903102464)]} 0 0
   [junit4]   2> 386427 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[126 (1536424032904151040)]} 0 0
   [junit4]   2> 386427 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[126 (1536424032904151040)]} 0 0
   [junit4]   2> 386428 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[127 (1536424032905199616)]} 0 0
   [junit4]   2> 386428 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[127 (1536424032905199616)]} 0 0
   [junit4]   2> 386429 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[128 (1536424032906248192)]} 0 0
   [junit4]   2> 386430 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[128 (1536424032906248192)]} 0 0
   [junit4]   2> 386430 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[129 (1536424032907296768)]} 0 0
   [junit4]   2> 386431 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[129 (1536424032908345344)]} 0 0
   [junit4]   2> 386432 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[130 (1536424032909393920)]} 0 0
   [junit4]   2> 386433 INFO  (qtp1045521447-3058) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[130 (1536424032910442496)]} 0 0
   [junit4]   2> 386434 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[131 (1536424032911491072)]} 0 0
   [junit4]   2> 386434 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[131 (1536424032911491072)]} 0 0
   [junit4]   2> 386435 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[132 (1536424032912539648)]} 0 0
   [junit4]   2> 386436 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[132 (1536424032912539648)]} 0 0
   [junit4]   2> 386436 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[133 (1536424032913588224)]} 0 0
   [junit4]   2> 386437 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[133 (1536424032914636800)]} 0 0
   [junit4]   2> 386437 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[134 (1536424032914636800)]} 0 0
   [junit4]   2> 386438 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[134 (1536424032915685376)]} 0 0
   [junit4]   2> 386439 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[135 (1536424032915685376)]} 0 0
   [junit4]   2> 386439 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[135 (1536424032916733952)]} 0 0
   [junit4]   2> 386440 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[136 (1536424032917782528)]} 0 0
   [junit4]   2> 386441 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[136 (1536424032917782528)]} 0 0
   [junit4]   2> 386441 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[137 (1536424032918831104)]} 0 0
   [junit4]   2> 386442 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[137 (1536424032919879680)]} 0 0
   [junit4]   2> 386442 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[138 (1536424032919879680)]} 0 0
   [junit4]   2> 386443 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[138 (1536424032920928256)]} 0 0
   [junit4]   2> 386444 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[139 (1536424032921976832)]} 0 0
   [junit4]   2> 386444 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[139 (1536424032921976832)]} 0 0
   [junit4]   2> 386445 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[140 (1536424032923025408)]} 0 0
   [junit4]   2> 386445 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[140 (1536424032923025408)]} 0 0
   [junit4]   2> 386446 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[141 (1536424032924073984)]} 0 0
   [junit4]   2> 386447 INFO  (qtp1045521447-3056) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[141 (1536424032924073984)]} 0 0
   [junit4]   2> 386448 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[142 (1536424032926171136)]} 0 0
   [junit4]   2> 386458 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[142 (1536424032936656896)]} 0 0
   [junit4]   2> 386459 INFO  (qtp1735073621-3037) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[143 (1536424032937705472)]} 0 0
   [junit4]   2> 386460 INFO  (qtp1045521447-3059) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[143 (1536424032937705472)]} 0 0
   [junit4]   2> 386460 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[144 (1536424032938754048)]} 0 0
   [junit4]   2> 386461 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[144 (1536424032939802624)]} 0 0
   [junit4]   2> 386461 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[145 (1536424032939802624)]} 0 0
   [junit4]   2> 386462 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[145 (1536424032940851200)]} 0 0
   [junit4]   2> 386463 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[146 (1536424032940851200)]} 0 0
   [junit4]   2> 386463 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[146 (1536424032941899776)]} 0 0
   [junit4]   2> 386464 INFO  (qtp1735073621-3040) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[147 (1536424032942948352)]} 0 0
   [junit4]   2> 386464 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[147 (1536424032942948352)]} 0 0
   [junit4]   2> 386465 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[148 (1536424032943996928)]} 0 0
   [junit4]   2> 386466 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[148 (1536424032943996928)]} 0 0
   [junit4]   2> 386466 INFO  (qtp1735073621-3041) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[149 (1536424032945045504)]} 0 0
   [junit4]   2> 386467 INFO  (qtp1045521447-3053) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={wt=javabin&version=2}{add=[149 (1536424032946094080)]} 0 0
   [junit4]   2> 386467 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 386526 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@334d6b23 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34a6d5fd),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@334d6b23 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34a6d5fd),segFN=segments_3,generation=3}
   [junit4]   2> 386526 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 386527 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7a7c539[collection1] main]
   [junit4]   2> 386527 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 386527 INFO  
(searcherExecutor-1536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7a7c539[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)
 Uninverting(_1(7.0.0):C59)))}
   [junit4]   2> 386528 INFO  (qtp1735073621-3034) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 60
   [junit4]   2> 386530 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 386617 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@47d0da6e 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f73b150),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@47d0da6e 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5f73b150),segFN=segments_3,generation=3}
   [junit4]   2> 386617 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 386618 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@244deaee[collection1] main]
   [junit4]   2> 386619 INFO  
(searcherExecutor-1547-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@244deaee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)
 Uninverting(_1(7.0.0):C59)))}
   [junit4]   2> 386619 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 386619 INFO  (qtp1045521447-3057) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/a_avk path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 89
   [junit4]   2> 386626 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 386626 INFO  (qtp1735073621-3038) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/a_avk path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 386630 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 386630 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/a_avk path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 386638 INFO  (qtp1735073621-3039) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/a_avk path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 387040 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/a_avk path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::213]:33332/a_avk|http://127.0.0.1:46252/a_avk/collection1&rows=10&version=2&q=*:*&NOW=1465248139755&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 387042 INFO  (qtp1045521447-3060) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/a_avk path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1465248139755&ids=11,1,16,115,117,7,139,8,108,109&isShard=true&shard.url=[ff01::213]:3333

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

=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=36,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=6708,transaction_logs_total_number=3}
   [junit4]   2> 436932 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 436932 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 
0 - closing IndexWriter
   [junit4]   2> 436932 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 436933 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 436963 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 
directories currently being tracked
   [junit4]   2> 436963 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard1/cores/collection1/data
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard1/cores/collection1/data;done=false>>]
   [junit4]   2> 436964 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 436964 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard1/cores/collection1/data
   [junit4]   2> 436964 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard1/cores/collection1/data/index
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard1/cores/collection1/data/index;done=false>>]
   [junit4]   2> 436964 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 436964 INFO  (coreCloseExecutor-1593-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001/tempDir-001/shard1/cores/collection1/data/index
   [junit4]   2> 436969 INFO  
(TEST-TestDistributedSearch.test-seed#[A5D189AD312CE909]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2d78f788{/a_avk,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=A5D189AD312CE909 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=es-EC -Dtests.timezone=Etc/GMT-9 -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 52.6s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A5D189AD312CE909:2D85B6779FD084F1]: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> 436972 INFO  
(SUITE-TestDistributedSearch-seed#[A5D189AD312CE909]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A5D189AD312CE909-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{other_tl1=PostingsFormat(name=Memory doPackFST= false), 
range_facet_l_dv=FST50, SubjectTerms_mfacet=PostingsFormat(name=Memory 
doPackFST= false), 
n_l1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 a_n_tdt=PostingsFormat(name=SimpleText), 
multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 intDefault=PostingsFormat(name=Memory doPackFST= false), 
n_td1=PostingsFormat(name=Memory doPackFST= false), 
n_d1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 n_f1=PostingsFormat(name=Memory doPackFST= false), 
range_facet_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 n_tl1=PostingsFormat(name=Memory doPackFST= false), oddField_s=FST50, 
n_tf1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
lowerfilt1and2=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
a_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
lowerfilt=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 id=FST50, text=PostingsFormat(name=SimpleText), 
timestamp=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 severity=PostingsFormat(name=SimpleText), 
foo_sev_enum=PostingsFormat(name=SimpleText), n_dt1=PostingsFormat(name=Memory 
doPackFST= false), 
a_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 b_n_tdt=PostingsFormat(name=Memory doPackFST= false), n_ti1=FST50, 
rnd_b=FST50, _version_=PostingsFormat(name=Memory doPackFST= false), 
foo_b=PostingsFormat(name=SimpleText), n_tdt1=PostingsFormat(name=Memory 
doPackFST= false), foo_d=FST50, 
range_facet_i_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 foo_f=PostingsFormat(name=SimpleText)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), 
foo_sev_enum=DocValuesFormat(name=Lucene54), 
range_facet_i_dv=DocValuesFormat(name=Lucene54), 
timestamp=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1949, 
maxMBSortInHeap=6.2931785721181, sim=ClassicSimilarity, locale=es-EC, 
timezone=Etc/GMT-9
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 
1.8.0_74 (64-bit)/cpus=4,threads=1,free=334151472,total=404226048
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestDynamicFieldCollectionResource, JavabinLoaderTest, 
AnalysisAfterCoreReloadTest, BitVectorTest, TestRemoteStreaming, 
CloneFieldUpdateProcessorFactoryTest, CdcrVersionReplicationTest, 
TestBinaryField, CacheHeaderTest, ForceLeaderTest, SolrCoreTest, 
DeleteLastCustomShardedReplicaTest, ReplicationFactorTest, TestOrdValues, 
QueryEqualityTest, TestJsonRequest, TestDistribDocBasedVersion, 
BlobRepositoryCloudTest, CopyFieldTest, 
ConcurrentDeleteAndCreateCollectionTest, TestLegacyFieldCache, 
SpatialRPTFieldTypeTest, ZkNodePropsTest, URLClassifyProcessorTest, 
HdfsChaosMonkeySafeLeaderTest, CursorPagingTest, TestExceedMaxTermLength, 
TestRealTimeGet, TestNRTOpen, TestFieldCacheSortRandom, OutputWriterTest, 
TestAnalyzedSuggestions, DistributedSpellCheckComponentTest, GraphQueryTest, 
SuggestComponentContextFilterQueryTest, CoreAdminCreateDiscoverTest, 
QueryElevationComponentTest, TestSortingResponseWriter, 
TestReplicationHandlerBackup, TestCloudBackupRestore, DateFieldTest, 
TestClusterStateMutator, TestDistribIDF, DateRangeFieldTest, 
TestArbitraryIndexDir, TestSizeLimitedDistributedMap, BlockDirectoryTest, 
TestMaxScoreQueryParser, SmileWriterTest, TestUninvertingReader, 
TestStressLucene, FileBasedSpellCheckerTest, CreateCollectionCleanupTest, 
TestDistributedSearch]
   [junit4] Completed [117/612 (1!)] on J0 in 52.68s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 1615 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:740: The 
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:684: The 
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:59: The 
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/common-build.xml:531:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1427:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:984:
 There were test failures: 612 suites (10 ignored), 2560 tests, 1 failure, 87 
ignored (72 assumptions) [seed: A5D189AD312CE909]

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




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