Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/204/
Java: 64bit/jdk1.8.0_141 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

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

Error Message:
Error from server at http://127.0.0.1:36753//collection1: ERROR: [doc=1] Error 
adding field 'severity'='Not Available' msg=EnumFieldType requires 
docValues="true".

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:36753//collection1: ERROR: [doc=1] Error adding 
field 'severity'='Not Available' msg=EnumFieldType requires docValues="true".
        at 
__randomizedtesting.SeedInfo.seed([A1F7BC792B343333:29A383A385C85ECB]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
        at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
        at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
        at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152)
        at 
org.apache.solr.BaseDistributedSearchTestCase.indexDoc(BaseDistributedSearchTestCase.java:483)
        at 
org.apache.solr.BaseDistributedSearchTestCase.indexr(BaseDistributedSearchTestCase.java:465)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:1052)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
        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:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 11939 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> 996995 INFO  
(SUITE-TestDistributedSearch-seed#[A1F7BC792B343333]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/init-core-data-001
   [junit4]   2> 996996 WARN  
(SUITE-TestDistributedSearch-seed#[A1F7BC792B343333]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=763 numCloses=763
   [junit4]   2> 996996 INFO  
(SUITE-TestDistributedSearch-seed#[A1F7BC792B343333]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 996996 INFO  
(SUITE-TestDistributedSearch-seed#[A1F7BC792B343333]-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> 996996 INFO  
(SUITE-TestDistributedSearch-seed#[A1F7BC792B343333]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 997050 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/control/cores/collection1
   [junit4]   2> 997051 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 997052 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1a903647{/,null,AVAILABLE}
   [junit4]   2> 997055 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4137ee73{HTTP/1.1,[http/1.1]}{127.0.0.1:36753}
   [junit4]   2> 997055 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] o.e.j.s.Server 
Started @998431ms
   [junit4]   2> 997055 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=36753, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/control/cores}
   [junit4]   2> 997055 ERROR 
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 997055 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 997055 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 997055 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 997055 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-08-05T04:09:02.166Z
   [junit4]   2> 997056 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/control/solr.xml
   [junit4]   2> 997059 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 997076 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d
   [junit4]   2> 997078 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d
   [junit4]   2> 997079 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d
   [junit4]   2> 997079 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/control/cores
   [junit4]   2> 997080 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 997086 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 997104 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 997116 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 997174 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 997178 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 997178 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d
   [junit4]   2> 997178 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 997178 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 997179 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3395111468241865]
   [junit4]   2> 997204 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 997204 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 997205 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 997205 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 997205 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 997206 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2d158b6b[collection1] main]
   [junit4]   2> 997206 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 997219 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 997270 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 997270 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 997270 INFO  (coreLoadExecutor-4563-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1574862735153299456
   [junit4]   2> 997270 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 997270 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 997270 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 997270 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 997270 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 997271 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 997271 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 997271 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 997272 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2d158b6b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 997677 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 997677 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 997678 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@48b664f9{/,null,AVAILABLE}
   [junit4]   2> 997678 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@660a4eea{HTTP/1.1,[http/1.1]}{127.0.0.1:32963}
   [junit4]   2> 997679 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] o.e.j.s.Server 
Started @999055ms
   [junit4]   2> 997679 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=32963, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/shard0/cores}
   [junit4]   2> 997679 ERROR 
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 997679 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 997679 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 997679 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 997679 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-08-05T04:09:02.790Z
   [junit4]   2> 997680 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 997682 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 997696 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d
   [junit4]   2> 997699 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d
   [junit4]   2> 997699 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d
   [junit4]   2> 997700 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/shard0/cores
   [junit4]   2> 997700 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 997701 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 997715 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 997732 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 997787 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 997790 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 997790 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@768ea28d
   [junit4]   2> 997791 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 997791 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 997791 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3395111468241865]
   [junit4]   2> 997814 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 997814 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 997815 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 997815 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 997815 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 997815 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3eb9c02a[collection1] main]
   [junit4]   2> 997816 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 997830 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 997884 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 997884 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 997885 INFO  (coreLoadExecutor-4574-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1574862735798173696
   [junit4]   2> 997885 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 997885 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 997885 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 997885 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 997885 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 997885 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 997886 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 997886 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 997887 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3eb9c02a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 998212 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 998215 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1574862736142106624)} 0 1
   [junit4]   2> 998218 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1574862736145252352)} 0 1
   [junit4]   2> 998221 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1574862736147349504)]} 0 2
   [junit4]   2> 998222 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1574862736150495232)]} 0 0
   [junit4]   2> 998223 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1574862736152592384)]} 0 0
   [junit4]   2> 998224 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1574862736152592384)]} 0 0
   [junit4]   2> 998225 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1574862736153640960)]} 0 0
   [junit4]   2> 998225 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1574862736154689536)]} 0 0
   [junit4]   2> 998226 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1574862736155738112)]} 0 0
   [junit4]   2> 998227 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1574862736156786688)]} 0 0
   [junit4]   2> 998228 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1574862736156786688)]} 0 0
   [junit4]   2> 998228 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1574862736157835264)]} 0 0
   [junit4]   2> 998229 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1574862736158883840)]} 0 0
   [junit4]   2> 998230 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1574862736159932416)]} 0 0
   [junit4]   2> 998231 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1574862736159932416)]} 0 0
   [junit4]   2> 998231 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1574862736160980992)]} 0 0
   [junit4]   2> 998232 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1574862736162029568)]} 0 0
   [junit4]   2> 998233 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1574862736162029568)]} 0 0
   [junit4]   2> 998233 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1574862736163078144)]} 0 0
   [junit4]   2> 998234 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1574862736164126720)]} 0 0
   [junit4]   2> 998234 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1574862736164126720,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 998234 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5c6fc549 
commitCommandVersion:1574862736164126720
   [junit4]   2> 998238 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@64dc349c[collection1] main]
   [junit4]   2> 998239 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 998241 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@64dc349c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c9)))}
   [junit4]   2> 998241 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 998241 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1574862736171466752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 998241 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5994944b 
commitCommandVersion:1574862736171466752
   [junit4]   2> 998245 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@24fd6245[collection1] main]
   [junit4]   2> 998245 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 998248 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@24fd6245[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c9)))}
   [junit4]   2> 998248 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 998252 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1574862736179855360)]} 0 3
   [junit4]   2> 998255 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1574862736183001088)]} 0 2
   [junit4]   2> 998256 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1574862736187195392)]} 0 0
   [junit4]   2> 998257 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1574862736187195392)]} 0 0
   [junit4]   2> 998257 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1574862736188243968)]} 0 0
   [junit4]   2> 998258 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1574862736189292544)]} 0 0
   [junit4]   2> 998258 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1574862736189292544)]} 0 0
   [junit4]   2> 998259 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1574862736190341120)]} 0 0
   [junit4]   2> 998260 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1574862736190341120)]} 0 0
   [junit4]   2> 998260 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1574862736191389696)]} 0 0
   [junit4]   2> 998261 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1574862736192438272)]} 0 0
   [junit4]   2> 998261 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1574862736192438272)]} 0 0
   [junit4]   2> 998262 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1574862736193486848)]} 0 0
   [junit4]   2> 998262 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1574862736193486848)]} 0 0
   [junit4]   2> 998263 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1574862736193486849)]} 0 0
   [junit4]   2> 998263 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1574862736194535424)]} 0 0
   [junit4]   2> 998264 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1574862736194535424)]} 0 0
   [junit4]   2> 998264 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1574862736195584000)]} 0 0
   [junit4]   2> 998264 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1574862736195584000)]} 0 0
   [junit4]   2> 998265 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1574862736196632576)]} 0 0
   [junit4]   2> 998265 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1574862736196632576)]} 0 0
   [junit4]   2> 998265 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1574862736196632577)]} 0 0
   [junit4]   2> 998266 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1574862736197681152)]} 0 0
   [junit4]   2> 998266 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1574862736197681152)]} 0 0
   [junit4]   2> 998266 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1574862736197681153)]} 0 0
   [junit4]   2> 998267 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1574862736198729728)]} 0 0
   [junit4]   2> 998267 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1574862736198729728)]} 0 0
   [junit4]   2> 998267 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1574862736198729729)]} 0 0
   [junit4]   2> 998268 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1574862736199778304)]} 0 0
   [junit4]   2> 998268 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1574862736199778304)]} 0 0
   [junit4]   2> 998268 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1574862736199778305)]} 0 0
   [junit4]   2> 998269 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1574862736199778305)]} 0 0
   [junit4]   2> 998269 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1574862736200826880)]} 0 0
   [junit4]   2> 998269 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1574862736200826880)]} 0 0
   [junit4]   2> 998270 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1574862736200826881)]} 0 0
   [junit4]   2> 998270 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1574862736201875456)]} 0 0
   [junit4]   2> 998270 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1574862736201875456)]} 0 0
   [junit4]   2> 998271 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1574862736201875457)]} 0 0
   [junit4]   2> 998271 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1574862736202924032)]} 0 0
   [junit4]   2> 998271 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1574862736202924032)]} 0 0
   [junit4]   2> 998271 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1574862736202924033)]} 0 0
   [junit4]   2> 998272 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1574862736203972608)]} 0 0
   [junit4]   2> 998272 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1574862736203972608)]} 0 0
   [junit4]   2> 998272 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1574862736203972609)]} 0 0
   [junit4]   2> 998272 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1574862736203972609)]} 0 0
   [junit4]   2> 998273 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1574862736205021184)]} 0 0
   [junit4]   2> 998273 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1574862736205021184)]} 0 0
   [junit4]   2> 998273 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1574862736205021185)]} 0 0
   [junit4]   2> 998273 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1574862736205021185)]} 0 0
   [junit4]   2> 998274 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1574862736206069760)]} 0 0
   [junit4]   2> 998274 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1574862736206069760)]} 0 0
   [junit4]   2> 998274 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1574862736206069761)]} 0 0
   [junit4]   2> 998274 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1574862736206069761)]} 0 0
   [junit4]   2> 998274 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1574862736206069762)]} 0 0
   [junit4]   2> 998275 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1574862736207118336)]} 0 0
   [junit4]   2> 998275 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1574862736207118336)]} 0 0
   [junit4]   2> 998275 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1574862736207118337)]} 0 0
   [junit4]   2> 998275 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1574862736207118337)]} 0 0
   [junit4]   2> 998275 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1574862736207118338)]} 0 0
   [junit4]   2> 998276 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1574862736207118338)]} 0 0
   [junit4]   2> 998276 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1574862736208166912)]} 0 0
   [junit4]   2> 998276 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1574862736208166912)]} 0 0
   [junit4]   2> 998276 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1574862736208166913)]} 0 0
   [junit4]   2> 998276 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1574862736208166913)]} 0 0
   [junit4]   2> 998277 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1574862736209215488)]} 0 0
   [junit4]   2> 998277 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1574862736209215488)]} 0 0
   [junit4]   2> 998277 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1574862736209215489)]} 0 0
   [junit4]   2> 998277 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1574862736209215489)]} 0 0
   [junit4]   2> 998277 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1574862736209215490)]} 0 0
   [junit4]   2> 998278 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1574862736210264064)]} 0 0
   [junit4]   2> 998278 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1574862736210264064)]} 0 0
   [junit4]   2> 998278 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1574862736210264065)]} 0 0
   [junit4]   2> 998278 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1574862736210264065)]} 0 0
   [junit4]   2> 998279 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1574862736211312640)]} 0 0
   [junit4]   2> 998279 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1574862736211312640)]} 0 0
   [junit4]   2> 998279 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1574862736211312641)]} 0 0
   [junit4]   2> 998281 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1574862736213409792)]} 0 0
   [junit4]   2> 998282 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1574862736214458368)]} 0 0
   [junit4]   2> 998282 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1574862736214458368)]} 0 0
   [junit4]   2> 998282 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1574862736214458369)]} 0 0
   [junit4]   2> 998283 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1574862736215506944)]} 0 0
   [junit4]   2> 998283 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1574862736215506944)]} 0 0
   [junit4]   2> 998283 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1574862736215506945)]} 0 0
   [junit4]   2> 998284 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1574862736215506945)]} 0 0
   [junit4]   2> 998284 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1574862736216555520)]} 0 0
   [junit4]   2> 998284 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1574862736216555520)]} 0 0
   [junit4]   2> 998285 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1574862736216555521)]} 0 0
   [junit4]   2> 998285 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1574862736217604096)]} 0 0
   [junit4]   2> 998285 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1574862736217604096)]} 0 0
   [junit4]   2> 998285 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1574862736217604097)]} 0 0
   [junit4]   2> 998285 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1574862736217604097)]} 0 0
   [junit4]   2> 998286 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1574862736218652672)]} 0 0
   [junit4]   2> 998286 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1574862736218652672)]} 0 0
   [junit4]   2> 998286 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1574862736218652673)]} 0 0
   [junit4]   2> 998286 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1574862736218652673)]} 0 0
   [junit4]   2> 998287 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1574862736219701248)]} 0 0
   [junit4]   2> 998287 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1574862736219701248)]} 0 0
   [junit4]   2> 998287 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1574862736219701249)]} 0 0
   [junit4]   2> 998287 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1574862736219701249)]} 0 0
   [junit4]   2> 998287 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1574862736219701250)]} 0 0
   [junit4]   2> 998288 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1574862736220749824)]} 0 0
   [junit4]   2> 998288 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1574862736220749824)]} 0 0
   [junit4]   2> 998288 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1574862736220749825)]} 0 0
   [junit4]   2> 998288 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1574862736220749825)]} 0 0
   [junit4]   2> 998289 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1574862736221798400)]} 0 0
   [junit4]   2> 998289 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1574862736221798400)]} 0 0
   [junit4]   2> 998289 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1574862736221798401)]} 0 0
   [junit4]   2> 998289 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1574862736221798401)]} 0 0
   [junit4]   2> 998290 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1574862736222846976)]} 0 0
   [junit4]   2> 998290 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1574862736222846976)]} 0 0
   [junit4]   2> 998290 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1574862736222846977)]} 0 0
   [junit4]   2> 998291 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1574862736222846977)]} 0 0
   [junit4]   2> 998291 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1574862736223895552)]} 0 0
   [junit4]   2> 998291 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1574862736223895552)]} 0 0
   [junit4]   2> 998291 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1574862736223895553)]} 0 0
   [junit4]   2> 998291 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1574862736223895553)]} 0 0
   [junit4]   2> 998292 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1574862736224944128)]} 0 0
   [junit4]   2> 998292 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1574862736224944128)]} 0 0
   [junit4]   2> 998292 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1574862736224944129,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 998292 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5c6fc549 
commitCommandVersion:1574862736224944129
   [junit4]   2> 998296 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7d53fb48[collection1] main]
   [junit4]   2> 998296 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 998297 INFO  
(searcherExecutor-4564-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7d53fb48[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c9)
 Uninverting(_1(7.1.0):c59)))}
   [junit4]   2> 998297 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 998298 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1574862736231235584,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 998298 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5994944b 
commitCommandVersion:1574862736231235584
   [junit4]   2> 998301 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@29ea718e[collection1] main]
   [junit4]   2> 998301 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 998302 INFO  
(searcherExecutor-4575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@29ea718e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c9)
 Uninverting(_1(7.1.0):c59)))}
   [junit4]   2> 998302 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 4
   [junit4]   2> 998303 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 998303 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 998303 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 998303 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 998304 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998312 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:32963//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1501906143415&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998312 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143415&ids=114,116,1001,107,118,9,109,120,10,131&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:32963//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998313 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:32963//collection1|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 998314 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998315 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1501906143425&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998316 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143425&ids=11,143,12,111,1,100,102,149,6,139&isShard=true&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998317 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 998317 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998318 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143429&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998319 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143429&ids=12,101,102,15,4,104,105,7,129,120&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998319 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998319 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998333 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143431&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998334 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143431&ids=11,144,145,112,146,135,125,114,1001,128&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998334 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:32963//collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=14
   [junit4]   2> 998335 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998336 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:32963//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1501906143446&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998336 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143446&ids=143,13,123,3,125,137,149,106,1001,8&isShard=true&shard.url=http://127.0.0.1:32963//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998337 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998337 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998338 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143449&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998339 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143449&ids=111,144,12,100,145,4,115,117,129,131&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998339 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998340 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998341 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143451&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998341 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143451&ids=122,1,133,112,101,147,104,1001,8,9&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998342 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998343 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998344 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143454&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998344 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143454&ids=121,134,102,136,15,137,118,119,109,141&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998344 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998345 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998346 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143457&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998347 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143457&ids=132,1,144,102,3,136,5,16,17,106&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998348 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998348 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998350 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1501906143460&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998350 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143460&ids=143,11,101,103,126,105,8,119,109,10&isShard=true&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998351 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998352 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998353 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1501906143463&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998353 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143463&ids=122,144,112,2,102,16,1001,118,8,107&isShard=true&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998354 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998354 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998355 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143466&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998356 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143466&ids=145,113,115,6,138,9,119,109,131,10&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998356 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998357 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998358 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1501906143469&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998360 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143469&ids=143,14,114,15,5,137,105,127,118,108&isShard=true&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998361 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 998362 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998362 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143473&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998363 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143473&ids=11,132,145,134,2,136,115,138,109,141&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998363 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998364 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998365 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143475&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998365 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143475&ids=143,123,3,147,126,16,148,117,119,140&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998366 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998367 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998368 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143478&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998368 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143478&ids=145,13,146,124,125,17,127,149,1001,120&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998369 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:32963//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998369 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998370 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:32963//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1501906143481&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998371 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143481&ids=122,112,101,102,3,147,114,125,16,109&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:32963//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998371 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:32963//collection1|[ff01::114]:33332/&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998372 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998372 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1501906143483&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998373 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143483&ids=12,2,134,14,136,148,149,116,106,142&isShard=true&shard.url=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998373 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1|[ff01::114]:33332/&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998374 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i_p+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 998375 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+desc&shard.url=http://127.0.0.1:32963//collection1&rows=10&version=2&q=*:*&NOW=1501906143486&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998375 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143486&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:32963//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998376 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1&sort=a_i_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998377 INFO  (qtp831492264-11207) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 998379 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i_p,5))+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:32963//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1501906143489&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998380 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143489&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:32963//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998380 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:32963//collection1|[ff01::083]:33332/&sort={!func}testfunc(add(a_i_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 998382 INFO  (qtp831492264-11213) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_p[*+TO+*]&distrib=false&sort=a_i_p+asc&wt=javabin&version=2} 
hits=13 status=0 QTime=0
   [junit4]   2> 998383 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:32963//collection1|[ff01::213]:33332/&rows=10&version=2&q=a_i_p[*+TO+*]&NOW=1501906143493&isShard=true&wt=javabin}
 hits=13 status=0 QTime=0
   [junit4]   2> 998384 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_p[*+TO+*]&df=text&distrib=false&shards.purpose=64&NOW=1501906143493&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:32963//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998384 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_p[*+TO+*]&shards=[ff01::114]:33332/|http://127.0.0.1:32963//collection1|[ff01::213]:33332/&sort=a_i_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=2
   [junit4]   2> 998385 INFO  (qtp831492264-11214) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i_p+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 998386 INFO  (qtp1176456236-11225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+asc,+id+desc&shard.url=http://127.0.0.1:32963//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1501906143497&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998387 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1501906143497&ids=11,146,147,15,4,148,16,149,17,6&isShard=true&shard.url=http://127.0.0.1:32963//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998387 INFO  (qtp1176456236-11230) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:32963//collection1|[ff01::213]:33332/&sort=a_i_p+asc,+id+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998388 INFO  (qtp831492264-11212) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i_p+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 998389 INFO  (qtp1176456236-11224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i_p+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:32963//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1501906143500&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 998390 INFO  (qtp1176456236-11229) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&fl=*,score&shards.purpose=64&NOW=1501906143500&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:32963//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 998390 INFO  (qtp1176456236-11231) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:32963//collection1|[ff01::114]:33332/&fl=*,score&sort=a_i_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 998391 INFO  

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

teProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: EnumFieldType 
requires docValues="true".
   [junit4]   2>        at 
org.apache.solr.schema.EnumFieldType.createFields(EnumFieldType.java:185)
   [junit4]   2>        at 
org.apache.solr.update.DocumentBuilder.addField(DocumentBuilder.java:66)
   [junit4]   2>        at 
org.apache.solr.update.DocumentBuilder.toDocument(DocumentBuilder.java:159)
   [junit4]   2>        ... 56 more
   [junit4]   2> 
   [junit4]   2> 1004114 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 1004115 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@4137ee73{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1004115 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1285307121
   [junit4]   2> 1004115 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1004116 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1004118 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1004118 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1004118 INFO  (coreCloseExecutor-4580-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1dc03eda
   [junit4]   2> 1004119 INFO  (coreCloseExecutor-4580-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=499138266
   [junit4]   2> 1004121 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1a903647{/,null,UNAVAILABLE}
   [junit4]   2> 1004121 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@660a4eea{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1004121 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1436300645
   [junit4]   2> 1004122 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1004122 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1004122 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1004122 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1004122 INFO  (coreCloseExecutor-4582-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2328efc0
   [junit4]   2> 1004122 INFO  (coreCloseExecutor-4582-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=589885376
   [junit4]   2> 1004122 INFO  
(TEST-TestDistributedSearch.test-seed#[A1F7BC792B343333]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@48b664f9{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=A1F7BC792B343333 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=de-AT -Dtests.timezone=Australia/Queensland -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   7.13s J2 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:36753//collection1: ERROR: [doc=1] Error adding 
field 'severity'='Not Available' msg=EnumFieldType requires docValues="true".
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A1F7BC792B343333:29A383A385C85ECB]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.indexDoc(BaseDistributedSearchTestCase.java:483)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.indexr(BaseDistributedSearchTestCase.java:465)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:1052)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A1F7BC792B343333-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=true): {}, locale=de-AT, 
timezone=Australia/Queensland
   [junit4]   2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation 
1.8.0_141 (64-bit)/cpus=8,threads=1,free=248303976,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [TestManagedSchemaAPI, 
TestConfigReload, DataDrivenBlockJoinTest, TestMultiWordSynonyms, 
TestStressCloudBlindAtomicUpdates, ShufflingReplicaListTransformerTest, 
TestBinaryResponseWriter, ZkStateReaderTest, TestTolerantSearch, TestTrie, 
MinimalSchemaTest, TestLFUCache, RecoveryZkTest, HdfsDirectoryFactoryTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, TestBulkSchemaConcurrent, 
ResponseHeaderTest, TestUseDocValuesAsStored2, PingRequestHandlerTest, 
AliasIntegrationTest, TestPseudoReturnFields, TestNumericRangeQuery32, 
TestHdfsCloudBackupRestore, TestGeoJSONResponseWriter, 
TestUniqueKeyFieldResource, NotRequiredUniqueKeyTest, CollectionReloadTest, 
TestDocBasedVersionConstraints, TestCloudSearcherWarming, 
PreAnalyzedFieldManagedSchemaCloudTest, XsltUpdateRequestHandlerTest, 
TestSolrCLIRunExample, RuleEngineTest, HdfsLockFactoryTest, 
TestSubQueryTransformerCrossCore, HdfsCollectionsAPIDistributedZkTest, 
AutoScalingHandlerTest, DistribJoinFromCollectionTest, 
HdfsBasicDistributedZkTest, TermsComponentTest, 
LeaderInitiatedRecoveryOnCommitTest, TestCollapseQParserPlugin, 
TestNumericTerms32, SolrMetricsIntegrationTest, TestSQLHandlerNonCloud, 
OverseerTest, TestRecovery, TestUtils, DocumentBuilderTest, 
TestFieldTypeResource, CdcrReplicationDistributedZkTest, 
TestSimpleQParserPlugin, TestQueryUtils, CustomCollectionTest, 
TestIndexSearcher, SuggesterFSTTest, AtomicUpdatesTest, 
TermVectorComponentDistributedTest, TestLeaderInitiatedRecoveryThread, 
SolrInfoBeanTest, CopyFieldTest, TestCopyFieldCollectionResource, 
ResponseLogComponentTest, RestartWhileUpdatingTest, MultiTermTest, 
OverseerModifyCollectionTest, CursorMarkTest, TestJmxIntegration, 
HdfsRecoveryZkTest, TestConfigOverlay, RankQueryTest, 
TestRandomRequestDistribution, QueryEqualityTest, 
SharedFSAutoReplicaFailoverUtilsTest, VersionInfoTest, 
FastVectorHighlighterTest, TestIntervalFaceting, TestSolrDeletionPolicy2, 
InfoHandlerTest, BJQParserTest, TestFieldCacheReopen, TestInitParams, 
TolerantUpdateProcessorTest, RegexBoostProcessorTest, 
TestMiniSolrCloudClusterSSL, TestMacros, NumericFieldsTest, 
TestSolrQueryResponse, TestPushWriter, SuggestComponentTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, SolrJmxReporterTest, 
BasicDistributedZk2Test, TestConfigSetsAPIZkFailure, BitVectorTest, 
CircularListTest, FullSolrCloudDistribCmdsTest, BlockJoinFacetSimpleTest, 
TestAnalyzeInfixSuggestions, CoreSorterTest, 
SignatureUpdateProcessorFactoryTest, SpellPossibilityIteratorTest, 
TestDistributedMissingSort, TestLRUStatsCache, TestCoreAdminApis, 
TestSolrCoreParser, TestRandomCollapseQParserPlugin, SecurityConfHandlerTest, 
MoveReplicaTest, TestSolrCoreProperties, TestReload, 
ClassificationUpdateProcessorTest, TestXmlQParserPlugin, PreAnalyzedFieldTest, 
UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, BasicZkTest, 
TestRandomFaceting, TestZkChroot, TestRandomDVFaceting, TestDistributedSearch]
   [junit4] Completed [330/727 (1!)] on J2 in 7.14s, 1 test, 1 error <<< 
FAILURES!

[...truncated 44657 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to