[JENKINS] Lucene-Solr-NightlyTests-6.6 - Build # 21 - Still Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.6/21/

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

Error Message:
Captured an uncaught exception in thread: Thread[id=177557, name=Thread-64868, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=177557, name=Thread-64868, state=RUNNABLE, 
group=TGRP-TestDistributedSearch]
        at 
__randomizedtesting.SeedInfo.seed([A51ABA2A36416BE0:2D4E85F098BD0618]:0)
Caused by: java.lang.AssertionError: Expected to find shardAddress in the up 
shard info
        at __randomizedtesting.SeedInfo.seed([A51ABA2A36416BE0]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1176)
        at 
org.apache.solr.TestDistributedSearch$1.run(TestDistributedSearch.java:1132)




Build Log:
[...truncated 13043 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/init-core-data-001
   [junit4]   2> 2384162 INFO  
(SUITE-TestDistributedSearch-seed#[A51ABA2A36416BE0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 2384163 INFO  
(SUITE-TestDistributedSearch-seed#[A51ABA2A36416BE0]-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> 2384163 INFO  
(SUITE-TestDistributedSearch-seed#[A51ABA2A36416BE0]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /pr_/ot
   [junit4]   2> 2384280 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/control/cores/collection1
   [junit4]   2> 2384281 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2384287 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@20dcab9d{/pr_/ot,null,AVAILABLE}
   [junit4]   2> 2384287 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4fed2380{HTTP/1.1,[http/1.1]}{127.0.0.1:40500}
   [junit4]   2> 2384287 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] o.e.j.s.Server 
Started @2387137ms
   [junit4]   2> 2384287 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/pr_/ot, 
hostPort=40500, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/control/cores}
   [junit4]   2> 2384288 ERROR 
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2384288 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.6.1
   [junit4]   2> 2384288 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 2384288 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2384288 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-11T13:56:20.441Z
   [junit4]   2> 2384288 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/control/solr.xml
   [junit4]   2> 2384301 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2384333 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/control/cores
   [junit4]   2> 2384333 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2384334 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2384373 ERROR (Finalizer) [    ] o.a.s.c.CoreContainer 
CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE 
RESOURCE LEAK!!!  instance=1902544743
   [junit4]   2> 2384381 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
   [junit4]   2> 2384407 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2384523 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2384529 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 2384529 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2384529 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 2384530 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@76249c02
   [junit4]   2> 2384532 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=5.05859375, 
floorSegmentMB=1.099609375, forceMergeDeletesPctAllowed=15.964015571751549, 
segmentsPerTier=24.0, maxCFSSegmentSizeMB=0.9755859375, 
noCFSRatio=0.8839965866222327
   [junit4]   2> 2384592 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2384592 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2384593 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2384593 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2384593 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=37, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=88.6318359375, 
floorSegmentMB=1.8095703125, forceMergeDeletesPctAllowed=23.837870005480312, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 2384594 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5ae782bd[collection1] main]
   [junit4]   2> 2384594 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2384608 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2384635 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2384635 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2384635 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 2147483647 transient cores
   [junit4]   2> 2384635 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2384635 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2384635 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2384636 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2384636 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2384636 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2384636 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2384637 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2384639 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5ae782bd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2384649 INFO  (coreLoadExecutor-10187-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1569916852163837952
   [junit4]   2> 2384957 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 2384958 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2384959 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@574e4a20{/pr_/ot,null,AVAILABLE}
   [junit4]   2> 2384960 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@23c5222e{HTTP/1.1,[http/1.1]}{127.0.0.1:35374}
   [junit4]   2> 2384960 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] o.e.j.s.Server 
Started @2387810ms
   [junit4]   2> 2384960 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/pr_/ot, 
hostPort=35374, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/shard0/cores}
   [junit4]   2> 2384961 ERROR 
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2384961 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.6.1
   [junit4]   2> 2384961 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 2384961 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2384961 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-11T13:56:21.114Z
   [junit4]   2> 2384962 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 2384968 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2384996 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/shard0/cores
   [junit4]   2> 2384996 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2384997 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2385034 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
   [junit4]   2> 2385062 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2385183 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2385189 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 2385189 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2385189 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A51ABA2A36416BE0-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 2385189 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@76249c02
   [junit4]   2> 2385191 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=5.05859375, 
floorSegmentMB=1.099609375, forceMergeDeletesPctAllowed=15.964015571751549, 
segmentsPerTier=24.0, maxCFSSegmentSizeMB=0.9755859375, 
noCFSRatio=0.8839965866222327
   [junit4]   2> 2385254 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2385254 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2385255 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2385255 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2385256 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=37, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=88.6318359375, 
floorSegmentMB=1.8095703125, forceMergeDeletesPctAllowed=23.837870005480312, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 2385257 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@31d9a7eb[collection1] main]
   [junit4]   2> 2385257 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2385264 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2385289 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2385289 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2385289 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2385289 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 2147483647 transient cores
   [junit4]   2> 2385289 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2385289 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2385289 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2385290 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2385290 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2385290 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2385290 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2385292 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@31d9a7eb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2385292 INFO  (coreLoadExecutor-10198-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1569916852838072320
   [junit4]   2> 2385507 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 2385511 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1569916853065613312)} 0 1
   [junit4]   2> 2385513 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1569916853068759040)} 0 1
   [junit4]   2> 2385517 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[1 (1569916853071904768)]} 0 2
   [junit4]   2> 2385525 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[1 (1569916853076099072)]} 0 5
   [junit4]   2> 2385527 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[2 (1569916853084487680)]} 0 0
   [junit4]   2> 2385534 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[2 (1569916853090779136)]} 0 0
   [junit4]   2> 2385536 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[3 (1569916853093924864)]} 0 0
   [junit4]   2> 2385538 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[3 (1569916853096022016)]} 0 0
   [junit4]   2> 2385547 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[4 (1569916853104410624)]} 0 0
   [junit4]   2> 2385549 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[4 (1569916853106507776)]} 0 0
   [junit4]   2> 2385551 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[5 (1569916853109653504)]} 0 0
   [junit4]   2> 2385553 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[5 (1569916853110702080)]} 0 0
   [junit4]   2> 2385555 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[6 (1569916853112799232)]} 0 0
   [junit4]   2> 2385557 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[6 (1569916853114896384)]} 0 0
   [junit4]   2> 2385559 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[7 (1569916853116993536)]} 0 0
   [junit4]   2> 2385562 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[7 (1569916853121187840)]} 0 0
   [junit4]   2> 2385567 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[8 (1569916853126430720)]} 0 0
   [junit4]   2> 2385569 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[8 (1569916853128527872)]} 0 0
   [junit4]   2> 2385571 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[9 (1569916853130625024)]} 0 0
   [junit4]   2> 2385573 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[9 (1569916853132722176)]} 0 0
   [junit4]   2> 2385575 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2385575 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@49754b42
   [junit4]   2> 2385581 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@63e75fbc[collection1] main]
   [junit4]   2> 2385584 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@63e75fbc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.1):c9)))}
   [junit4]   2> 2385584 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2385584 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 9
   [junit4]   2> 2385586 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2385586 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6fd842df
   [junit4]   2> 2385591 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7f7cfa85[collection1] main]
   [junit4]   2> 2385592 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2385593 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7f7cfa85[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.1):c9)))}
   [junit4]   2> 2385594 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 2385597 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[10 (1569916853155790848)]} 0 1
   [junit4]   2> 2385599 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[10 (1569916853158936576)]} 0 1
   [junit4]   2> 2385601 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[11 (1569916853162082304)]} 0 0
   [junit4]   2> 2385603 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[11 (1569916853164179456)]} 0 0
   [junit4]   2> 2385605 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[12 (1569916853166276608)]} 0 0
   [junit4]   2> 2385607 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[12 (1569916853168373760)]} 0 0
   [junit4]   2> 2385609 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[13 (1569916853170470912)]} 0 0
   [junit4]   2> 2385611 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[13 (1569916853172568064)]} 0 0
   [junit4]   2> 2385613 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[1001 (1569916853174665216)]} 0 0
   [junit4]   2> 2385617 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[1001 (1569916853178859520)]} 0 0
   [junit4]   2> 2385620 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[14 (1569916853182005248)]} 0 0
   [junit4]   2> 2385622 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[14 (1569916853184102400)]} 0 0
   [junit4]   2> 2385624 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[15 (1569916853185150976)]} 0 0
   [junit4]   2> 2385626 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[15 (1569916853187248128)]} 0 0
   [junit4]   2> 2385628 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[16 (1569916853189345280)]} 0 0
   [junit4]   2> 2385629 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[16 (1569916853191442432)]} 0 0
   [junit4]   2> 2385631 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[17 (1569916853193539584)]} 0 0
   [junit4]   2> 2385633 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[17 (1569916853195636736)]} 0 0
   [junit4]   2> 2385635 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[100 (1569916853197733888)]} 0 0
   [junit4]   2> 2385637 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[100 (1569916853199831040)]} 0 0
   [junit4]   2> 2385639 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[101 (1569916853201928192)]} 0 0
   [junit4]   2> 2385641 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[101 (1569916853204025344)]} 0 0
   [junit4]   2> 2385643 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[102 (1569916853206122496)]} 0 0
   [junit4]   2> 2385645 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[102 (1569916853208219648)]} 0 0
   [junit4]   2> 2385647 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[103 (1569916853209268224)]} 0 0
   [junit4]   2> 2385648 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[103 (1569916853211365376)]} 0 0
   [junit4]   2> 2385650 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[104 (1569916853213462528)]} 0 0
   [junit4]   2> 2385652 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[104 (1569916853215559680)]} 0 0
   [junit4]   2> 2385654 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[105 (1569916853216608256)]} 0 0
   [junit4]   2> 2385656 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[105 (1569916853218705408)]} 0 0
   [junit4]   2> 2385658 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[106 (1569916853220802560)]} 0 0
   [junit4]   2> 2385659 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[106 (1569916853222899712)]} 0 0
   [junit4]   2> 2385661 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[107 (1569916853224996864)]} 0 0
   [junit4]   2> 2385663 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[107 (1569916853227094016)]} 0 0
   [junit4]   2> 2385665 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[108 (1569916853229191168)]} 0 0
   [junit4]   2> 2385667 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[108 (1569916853230239744)]} 0 0
   [junit4]   2> 2385670 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[109 (1569916853232336896)]} 0 1
   [junit4]   2> 2385672 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[109 (1569916853236531200)]} 0 0
   [junit4]   2> 2385674 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[110 (1569916853238628352)]} 0 0
   [junit4]   2> 2385676 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[110 (1569916853239676928)]} 0 0
   [junit4]   2> 2385678 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[111 (1569916853241774080)]} 0 0
   [junit4]   2> 2385679 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[111 (1569916853243871232)]} 0 0
   [junit4]   2> 2385681 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[112 (1569916853245968384)]} 0 0
   [junit4]   2> 2385683 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[112 (1569916853248065536)]} 0 0
   [junit4]   2> 2385685 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[113 (1569916853249114112)]} 0 0
   [junit4]   2> 2385686 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[113 (1569916853251211264)]} 0 0
   [junit4]   2> 2385688 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[114 (1569916853253308416)]} 0 0
   [junit4]   2> 2385690 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[114 (1569916853255405568)]} 0 0
   [junit4]   2> 2385692 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[115 (1569916853256454144)]} 0 0
   [junit4]   2> 2385694 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[115 (1569916853258551296)]} 0 0
   [junit4]   2> 2385695 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[116 (1569916853260648448)]} 0 0
   [junit4]   2> 2385697 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[116 (1569916853262745600)]} 0 0
   [junit4]   2> 2385699 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[117 (1569916853264842752)]} 0 0
   [junit4]   2> 2385701 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[117 (1569916853266939904)]} 0 0
   [junit4]   2> 2385703 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[118 (1569916853267988480)]} 0 0
   [junit4]   2> 2385704 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[118 (1569916853270085632)]} 0 0
   [junit4]   2> 2385706 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[119 (1569916853272182784)]} 0 0
   [junit4]   2> 2385708 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[119 (1569916853274279936)]} 0 0
   [junit4]   2> 2385710 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[120 (1569916853275328512)]} 0 0
   [junit4]   2> 2385711 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[120 (1569916853277425664)]} 0 0
   [junit4]   2> 2385713 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[121 (1569916853279522816)]} 0 0
   [junit4]   2> 2385715 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[121 (1569916853281619968)]} 0 0
   [junit4]   2> 2385717 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[122 (1569916853283717120)]} 0 0
   [junit4]   2> 2385719 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[122 (1569916853284765696)]} 0 0
   [junit4]   2> 2385720 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[123 (1569916853286862848)]} 0 0
   [junit4]   2> 2385722 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[123 (1569916853288960000)]} 0 0
   [junit4]   2> 2385724 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[124 (1569916853291057152)]} 0 0
   [junit4]   2> 2385726 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[124 (1569916853293154304)]} 0 0
   [junit4]   2> 2385728 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[125 (1569916853295251456)]} 0 0
   [junit4]   2> 2385730 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[125 (1569916853296300032)]} 0 0
   [junit4]   2> 2385732 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[126 (1569916853298397184)]} 0 0
   [junit4]   2> 2385733 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[126 (1569916853300494336)]} 0 0
   [junit4]   2> 2385735 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[127 (1569916853302591488)]} 0 0
   [junit4]   2> 2385737 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[127 (1569916853304688640)]} 0 0
   [junit4]   2> 2385739 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[128 (1569916853305737216)]} 0 0
   [junit4]   2> 2385740 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[128 (1569916853307834368)]} 0 0
   [junit4]   2> 2385742 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[129 (1569916853309931520)]} 0 0
   [junit4]   2> 2385744 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[129 (1569916853312028672)]} 0 0
   [junit4]   2> 2385746 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[130 (1569916853314125824)]} 0 0
   [junit4]   2> 2385748 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[130 (1569916853315174400)]} 0 0
   [junit4]   2> 2385749 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[131 (1569916853317271552)]} 0 0
   [junit4]   2> 2385751 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[131 (1569916853319368704)]} 0 0
   [junit4]   2> 2385753 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[132 (1569916853321465856)]} 0 0
   [junit4]   2> 2385755 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[132 (1569916853323563008)]} 0 0
   [junit4]   2> 2385757 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[133 (1569916853324611584)]} 0 0
   [junit4]   2> 2385759 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[133 (1569916853326708736)]} 0 0
   [junit4]   2> 2385760 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[134 (1569916853328805888)]} 0 0
   [junit4]   2> 2385762 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[134 (1569916853330903040)]} 0 0
   [junit4]   2> 2385764 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[135 (1569916853333000192)]} 0 0
   [junit4]   2> 2385768 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[135 (1569916853337194496)]} 0 0
   [junit4]   2> 2385770 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[136 (1569916853339291648)]} 0 0
   [junit4]   2> 2385772 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[136 (1569916853341388800)]} 0 0
   [junit4]   2> 2385774 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[137 (1569916853343485952)]} 0 0
   [junit4]   2> 2385776 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[137 (1569916853344534528)]} 0 0
   [junit4]   2> 2385777 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[138 (1569916853346631680)]} 0 0
   [junit4]   2> 2385779 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[138 (1569916853348728832)]} 0 0
   [junit4]   2> 2385781 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[139 (1569916853350825984)]} 0 0
   [junit4]   2> 2385783 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[139 (1569916853352923136)]} 0 0
   [junit4]   2> 2385785 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[140 (1569916853355020288)]} 0 0
   [junit4]   2> 2385787 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[140 (1569916853357117440)]} 0 0
   [junit4]   2> 2385789 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[141 (1569916853358166016)]} 0 0
   [junit4]   2> 2385790 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[141 (1569916853360263168)]} 0 0
   [junit4]   2> 2385792 INFO  (qtp329997060-129752) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[142 (1569916853362360320)]} 0 0
   [junit4]   2> 2385794 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[142 (1569916853364457472)]} 0 0
   [junit4]   2> 2385796 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[143 (1569916853366554624)]} 0 0
   [junit4]   2> 2385798 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[143 (1569916853367603200)]} 0 0
   [junit4]   2> 2385800 INFO  (qtp329997060-129749) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[144 (1569916853369700352)]} 0 0
   [junit4]   2> 2385802 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[144 (1569916853371797504)]} 0 0
   [junit4]   2> 2385804 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[145 (1569916853373894656)]} 0 0
   [junit4]   2> 2385806 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[145 (1569916853375991808)]} 0 0
   [junit4]   2> 2385808 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[146 (1569916853378088960)]} 0 0
   [junit4]   2> 2385810 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[146 (1569916853380186112)]} 0 0
   [junit4]   2> 2385812 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[147 (1569916853382283264)]} 0 0
   [junit4]   2> 2385814 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[147 (1569916853385428992)]} 0 0
   [junit4]   2> 2385816 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[148 (1569916853387526144)]} 0 0
   [junit4]   2> 2385818 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[148 (1569916853389623296)]} 0 0
   [junit4]   2> 2385820 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[149 (1569916853391720448)]} 0 0
   [junit4]   2> 2385823 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={wt=javabin&version=2}{add=[149 (1569916853393817600)]} 0 0
   [junit4]   2> 2385824 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2385824 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@49754b42
   [junit4]   2> 2385854 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@655e6f3d[collection1] main]
   [junit4]   2> 2385856 INFO  
(searcherExecutor-10188-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@655e6f3d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.1):c9)
 Uninverting(_1(6.6.1):c59)))}
   [junit4]   2> 2385856 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2385856 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 32
   [junit4]   2> 2385858 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2385858 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6fd842df
   [junit4]   2> 2385917 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@571f9be7[collection1] main]
   [junit4]   2> 2385918 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2385919 INFO  
(searcherExecutor-10199-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@571f9be7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.1):c9)
 Uninverting(_1(6.6.1):c59)))}
   [junit4]   2> 2385920 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/pr_/ot path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 61
   [junit4]   2> 2385922 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 2385922 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 2385924 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 2385924 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 2385926 INFO  (qtp329997060-129750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 2385947 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382087&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2385952 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382087&ids=132,13,14,5,128,7,129,107,119,141&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2385954 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=20
   [junit4]   2> 2385954 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2385994 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382145&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2385996 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382145&ids=132,13,14,5,128,7,129,107,119,141&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2385997 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 2386000 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382151&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386002 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382151&ids=132,13,14,5,128,7,129,107,119,141&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386003 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386007 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382145&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386010 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382145&ids=132,13,14,5,128,7,129,107,119,141&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386011 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=18
   [junit4]   2> 2386014 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382165&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386016 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382165&ids=132,13,14,5,128,7,129,107,119,141&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386017 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386023 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382144&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386026 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382144&ids=132,13,14,5,128,7,129,107,119,141&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386027 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=35
   [junit4]   2> 2386030 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382181&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386032 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382181&ids=132,13,14,5,128,7,129,107,119,141&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386033 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386035 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 2386196 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382191&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386198 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382191&ids=101,146,137,105,6,1001,117,139,109,142&isShard=true&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386200 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=162
   [junit4]   2> 2386200 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2386203 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382355&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386204 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382355&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386205 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382355&ids=101,146,137,105,6,1001,117,139,109,142&isShard=true&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386206 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382357&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386207 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386209 INFO  (qtp665845663-129765) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382355&ids=101,146,137,105,6,1001,117,139,109,142&isShard=true&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386209 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382357&ids=101,146,137,105,6,1001,117,139,109,142&isShard=true&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386210 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 2386211 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 2386211 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382362&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386213 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382362&ids=101,146,137,105,6,1001,117,139,109,142&isShard=true&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386214 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 2386214 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382366&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386215 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382365&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386216 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382366&ids=101,146,137,105,6,1001,117,139,109,142&isShard=true&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386218 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382365&ids=101,146,137,105,6,1001,117,139,109,142&isShard=true&shard.url=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386218 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 2386219 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::114]:33332/pr_/ot|[ff01::213]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 2386221 INFO  (qtp329997060-129751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 2386226 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382376&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386228 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382376&ids=143,124,136,148,137,17,138,6,7,120&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386229 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 2386230 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2386233 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382385&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386233 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382385&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386234 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382385&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386236 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382385&ids=143,124,136,148,137,17,138,6,7,120&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386236 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386237 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382385&ids=143,124,136,148,137,17,138,6,7,120&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386238 INFO  (qtp665845663-129794) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 2386240 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382385&ids=143,124,136,148,137,17,138,6,7,120&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386241 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382392&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386242 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382392&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386242 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 2386244 INFO  (qtp665845663-129794) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382392&ids=143,124,136,148,137,17,138,6,7,120&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386245 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 2386245 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382392&ids=143,124,136,148,137,17,138,6,7,120&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 2386246 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382397&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386246 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 2386248 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382397&ids=143,124,136,148,137,17,138,6,7,120&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386249 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386253 INFO  (qtp329997060-129748) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 2386258 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382409&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386260 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382409&ids=12,4,5,16,116,117,139,9,140,10&isShard=true&shard.url=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386261 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 2386262 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2386265 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382416&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386266 INFO  (qtp665845663-129794) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382417&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386266 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382417&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386267 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382416&ids=12,4,5,16,116,117,139,9,140,10&isShard=true&shard.url=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386268 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386269 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382417&ids=12,4,5,16,116,117,139,9,140,10&isShard=true&shard.url=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386270 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 2386271 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382417&ids=12,4,5,16,116,117,139,9,140,10&isShard=true&shard.url=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386272 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 2386273 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382424&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386274 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382425&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386275 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382424&ids=12,4,5,16,116,117,139,9,140,10&isShard=true&shard.url=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386276 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386276 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382425&ids=12,4,5,16,116,117,139,9,140,10&isShard=true&shard.url=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386277 INFO  (qtp665845663-129771) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386278 INFO  (qtp665845663-129769) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382429&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386280 INFO  (qtp665845663-129770) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382429&ids=12,4,5,16,116,117,139,9,140,10&isShard=true&shard.url=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386281 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=[ff01::213]:33332/pr_/ot|[ff01::114]:33332/pr_/ot|http://127.0.0.1:35374/pr_/ot/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2386283 INFO  (qtp329997060-129753) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 2386287 INFO  (qtp665845663-129767) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382438&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2386290 INFO  (qtp665845663-129766) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497189382438&ids=100,111,12,135,14,5,6,107,129,131&isShard=true&shard.url=http://127.0.0.1:35374/pr_/ot/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2386291 INFO  (qtp665845663-129795) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot path=/select 
params={q=*:*&shards=http://127.0.0.1:35374/pr_/ot/collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 2386291 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2386295 INFO  (qtp665845663-129772) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/pr_/ot 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:35374/pr_/ot/collection1&rows=10&version=2&q=*:*&NOW=1497189382446&isShard=tru

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

test-seed#[A51ABA2A36416BE0]) [    ] o.a.s.m.SolrMetricManager Closing metric 
reporters for: solr.node
   [junit4]   2> 2739389 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2739389 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2739390 INFO  (coreCloseExecutor-10304-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1e19dde5
   [junit4]   2> 2739465 INFO  (coreCloseExecutor-10304-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 2739467 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@223f7ead{/pr_/ot,null,UNAVAILABLE}
   [junit4]   2> 2739469 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@16d030be{HTTP/1.1,[http/1.1]}{127.0.0.1:49213}
   [junit4]   2> 2739469 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1303279665
   [junit4]   2> 2739470 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2739470 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2739470 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2739471 INFO  (coreCloseExecutor-10306-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@65d4d788
   [junit4]   2> 2739521 INFO  (coreCloseExecutor-10306-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 2739523 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3089d61c{/pr_/ot,null,UNAVAILABLE}
   [junit4]   2> 2739525 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@7427f8d1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2739525 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=263888424
   [junit4]   2> 2739525 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2739525 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2739525 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2739526 INFO  (coreCloseExecutor-10308-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1d4a4025
   [junit4]   2> 2739553 INFO  (coreCloseExecutor-10308-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 2739556 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4e382bce{/pr_/ot,null,UNAVAILABLE}
   [junit4]   2> 2739559 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@472ca0cb{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2739559 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=125183161
   [junit4]   2> 2739559 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2739559 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 2739559 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 2739563 INFO  (coreCloseExecutor-10310-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@b2ee6e4
   [junit4]   2> 2739579 INFO  (coreCloseExecutor-10310-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 2739582 INFO  
(TEST-TestDistributedSearch.test-seed#[A51ABA2A36416BE0]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1ecb7777{/pr_/ot,null,UNAVAILABLE}
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=A51ABA2A36416BE0 -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/test-data/enwiki.random.lines.txt
 -Dtests.locale=tr-TR -Dtests.timezone=America/Montreal -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR    355s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=177557, name=Thread-64868, state=RUNNABLE, 
group=TGRP-TestDistributedSearch]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A51ABA2A36416BE0:2D4E85F098BD0618]:0)
   [junit4]    > Caused by: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A51ABA2A36416BE0]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1176)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch$1.run(TestDistributedSearch.java:1132)
   [junit4]   2> 2739586 INFO  
(SUITE-TestDistributedSearch-seed#[A51ABA2A36416BE0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2739586 INFO  
(SUITE-TestDistributedSearch-seed#[A51ABA2A36416BE0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62), 
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=tr-TR, 
timezone=America/Montreal
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_121 (64-bit)/cpus=4,threads=2,free=62957656,total=529530880
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestSolrCloudWithSecureImpersonation, FastVectorHighlighterTest, 
TestManagedSchemaAPI, TestUninvertingReader, TestAuthenticationFramework, 
TestRestManager, HdfsBasicDistributedZk2Test, TestCoreAdminApis, 
GraphQueryTest, BlobRepositoryCloudTest, BlockJoinFacetDistribTest, 
SolrMetricReporterTest, TestFieldTypeResource, TestCustomStream, 
XsltUpdateRequestHandlerTest, TestPerFieldSimilarity, TestRandomFlRTGCloud, 
HdfsLockFactoryTest, TestSchemaManager, TestLRUStatsCache, TestRealTimeGet, 
TestJettySolrRunner, RankQueryTest, TestBinaryResponseWriter, 
TestGraphMLResponseWriter, TestFreeTextSuggestions, TestFieldCacheReopen, 
TestSolrConfigHandlerConcurrent, ShufflingReplicaListTransformerTest, 
TestUseDocValuesAsStored2, TestBlobHandler, TestCrossCoreJoin, BasicZkTest, 
MBeansHandlerTest, ResponseHeaderTest, TestDocumentBuilder, 
TestLegacyFieldCache, TestJsonFacetRefinement, TestRemoteStreaming, 
QueryElevationComponentTest, TestDFISimilarityFactory, 
TestPerFieldSimilarityWithDefaultOverride, TestCloudRecovery, TestFastWriter, 
ClassificationUpdateProcessorIntegrationTest, HighlighterMaxOffsetTest, 
SyncSliceTest, TestFieldCacheSortRandom, 
OverseerCollectionConfigSetProcessorTest, TestGraphTermsQParserPlugin, 
TestCollationFieldDocValues, TestPathTrie, TestOrdValues, TestFunctionQuery, 
TestSSLRandomization, TestDownShardTolerantSearch, TestCodecSupport, 
TestConfigSetsAPI, UpdateLogTest, HdfsSyncSliceTest, TestStressRecovery, 
MetricUtilsTest, TestFaceting, BasicDistributedZkTest, UUIDFieldTest, 
TestScoreJoinQPNoScore, TlogReplayBufferedWhileIndexingTest, 
TestFoldingMultitermQuery, TestRTimerTree, TestIntervalFaceting, 
TestConfigSetProperties, TestRandomFaceting, DateFieldTest, 
SolrIndexMetricsTest, PathHierarchyTokenizerFactoryTest, 
CdcrReplicationHandlerTest, TestRangeQuery, OverseerStatusTest, TestCryptoKeys, 
TestCloudManagedSchema, ZkNodePropsTest, TestJmxIntegration, 
CollectionReloadTest, TestBackupRepositoryFactory, 
DistributedFacetPivotSmallAdvancedTest, TestReplicationHandler, 
CurrencyFieldOpenExchangeTest, TestHdfsUpdateLog, TestReloadDeadlock, 
TestReplicaProperties, TestNoOpRegenerator, TestApiFramework, NodeMutatorTest, 
TestReplicationHandlerBackup, PolyFieldTest, TestCustomSort, TestFastLRUCache, 
TestMaxScoreQueryParser, SuggestComponentTest, OutputWriterTest, 
MissingSegmentRecoveryTest, TestSolrDeletionPolicy2, TestSolrConfigHandler, 
BlockDirectoryTest, SecurityConfHandlerTest, TestPHPSerializedResponseWriter, 
QueryEqualityTest, TestStressLiveNodes, SignatureUpdateProcessorFactoryTest, 
SynonymTokenizerTest, TestHighlightDedupGrouping, 
PKIAuthenticationIntegrationTest, AlternateDirectoryTest, 
ExternalFileFieldSortTest, JvmMetricsTest, SolrCoreTest, 
AtomicUpdateProcessorFactoryTest, ZkSolrClientTest, ZkCLITest, 
TestDistributedSearch]
   [junit4] Completed [501/711 (1!)] on J0 in 355.60s, 1 test, 1 error <<< 
FAILURES!

[...truncated 44110 lines...]

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