Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1753/
Java: 64bit/jdk1.8.0_201 -XX:+UseCompressedOops -XX:+UseParallelGC

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

Error Message:
Captured an uncaught exception in thread: Thread[id=10051, 
name=httpShardExecutor-6828-thread-10, state=RUNNABLE, 
group=TGRP-TestDistributedSearch]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=10051, name=httpShardExecutor-6828-thread-10, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]
Caused by: java.util.concurrent.RejectedExecutionException: Task 
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@4df9b287 
rejected from 
java.util.concurrent.ScheduledThreadPoolExecutor@46c4ca3b[Terminated, pool size 
= 1, active threads = 0, queued tasks = 0, completed tasks = 0]
        at __randomizedtesting.SeedInfo.seed([733FCD7EFE6D2E04]:0)
        at 
java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
        at 
java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
        at 
java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
        at 
java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
        at 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
        at 
org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 14625 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> 757884 INFO  
(SUITE-TestDistributedSearch-seed#[733FCD7EFE6D2E04]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
   [junit4]   2> 757886 INFO  
(SUITE-TestDistributedSearch-seed#[733FCD7EFE6D2E04]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\data-dir-85-001
   [junit4]   2> 757886 WARN  
(SUITE-TestDistributedSearch-seed#[733FCD7EFE6D2E04]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
   [junit4]   2> 757887 INFO  
(SUITE-TestDistributedSearch-seed#[733FCD7EFE6D2E04]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 757891 INFO  
(SUITE-TestDistributedSearch-seed#[733FCD7EFE6D2E04]-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> 757891 INFO  
(SUITE-TestDistributedSearch-seed#[733FCD7EFE6D2E04]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 757891 INFO  
(SUITE-TestDistributedSearch-seed#[733FCD7EFE6D2E04]-worker) [     ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /u/l
   [junit4]   2> 758701 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\control\cores\collection1
   [junit4]   2> 758706 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 758706 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 758706 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 758706 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
   [junit4]   2> 758706 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 758706 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 758706 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 758706 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2fe5380b{/u/l,null,AVAILABLE}
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@b4696ce{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:49234}
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.Server Started @758751ms
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/u/l, 
hostPort=49234, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\control\cores}
   [junit4]   2> 758708 ERROR 
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.8.0
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-10-25T03:58:42.776Z
   [junit4]   2> 758708 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\control\solr.xml
   [junit4]   2> 758718 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 758718 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 758719 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 758931 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 758932 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@61101172[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 758932 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@61101172[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 758938 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7ef686b6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 758938 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@7ef686b6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 758940 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.CoreContainer Not all security plugins configured!  
authentication=disabled authorization=disabled.  Solr is only as secure as you 
make it. Consider configuring authentication/authorization before exposing Solr 
to users internal or external.  See https://s.apache.org/solrsecurity for more 
info
   [junit4]   2> 758982 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 758984 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 759025 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba
   [junit4]   2> 759026 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba
   [junit4]   2> 759026 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba
   [junit4]   2> 759032 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\control\cores
   [junit4]   2> 759032 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 759068 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
   [junit4]   2> 759097 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 759178 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field 
id
   [junit4]   2> 759191 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 759191 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba
   [junit4]   2> 759191 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\control\cores\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 759191 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=14, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=60.412109375, 
floorSegmentMB=0.666015625, forceMergeDeletesPctAllowed=28.390016934199355, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=24.181035116566203
   [junit4]   2> 759291 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 759291 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 759291 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 759291 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 759291 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 759291 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 759306 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 759360 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 759360 INFO  (coreLoadExecutor-6666-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1681494715586838528
   [junit4]   2> 759361 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 759362 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 759362 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 759362 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 759362 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 759363 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 759363 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 759363 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 759363 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 759363 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 760354 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\shard0\cores\collection1
   [junit4]   2> 760354 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 760354 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 760354 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 760354 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
   [junit4]   2> 760354 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 760354 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1fe5712d{/u/l,null,AVAILABLE}
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@29092bc3{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:49265}
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.s.Server Started @760403ms
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/u/l, 
hostPort=49265, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\shard0\cores}
   [junit4]   2> 760359 ERROR 
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.8.0
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-10-25T03:58:44.427Z
   [junit4]   2> 760359 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 760369 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 760369 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 760369 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 760401 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 760402 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@782ebd12[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 760402 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@782ebd12[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 760407 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7839f37a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 760407 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@7839f37a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 760408 WARN  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.CoreContainer Not all security plugins configured!  
authentication=disabled authorization=disabled.  Solr is only as secure as you 
make it. Consider configuring authentication/authorization before exposing Solr 
to users internal or external.  See https://s.apache.org/solrsecurity for more 
info
   [junit4]   2> 760442 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 760442 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 760470 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba
   [junit4]   2> 760474 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba
   [junit4]   2> 760474 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba
   [junit4]   2> 760478 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\shard0\cores
   [junit4]   2> 760478 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 760511 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
   [junit4]   2> 760530 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 760603 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field 
id
   [junit4]   2> 760611 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 760611 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7a4e24ba
   [junit4]   2> 760611 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\shard0\cores\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_733FCD7EFE6D2E04-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 760615 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=14, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=60.412109375, 
floorSegmentMB=0.666015625, forceMergeDeletesPctAllowed=28.390016934199355, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=24.181035116566203
   [junit4]   2> 760722 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 760722 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 760724 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 760724 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 760725 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 760726 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 760737 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 760791 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 760791 INFO  (coreLoadExecutor-6684-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1681494717087350784
   [junit4]   2> 760792 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 760793 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 760793 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 760793 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 760793 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 760793 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 760794 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 760794 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 760795 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 760796 INFO  
(TEST-TestDistributedSearch.test-seed#[733FCD7EFE6D2E04]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 760796 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 760807 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1681494717102030848)} 0 2
   [junit4]   2> 760812 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1681494717105176576)} 0 3
   [junit4]   2> 760818 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[1 (1681494717110419456)]} 0 7
   [junit4]   2> 760823 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[1 (1681494717115662336)]} 0 2
   [junit4]   2> 760825 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[2 (1681494717120905216)]} 0 1
   [junit4]   2> 760827 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[2 (1681494717123002368)]} 0 1
   [junit4]   2> 760828 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[3 (1681494717125099520)]} 0 0
   [junit4]   2> 760828 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[3 (1681494717126148096)]} 0 0
   [junit4]   2> 760829 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[4 (1681494717127196672)]} 0 0
   [junit4]   2> 760829 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[4 (1681494717127196672)]} 0 0
   [junit4]   2> 760830 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[5 (1681494717128245248)]} 0 0
   [junit4]   2> 760831 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[5 (1681494717128245248)]} 0 0
   [junit4]   2> 760831 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[6 (1681494717129293824)]} 0 0
   [junit4]   2> 760831 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[6 (1681494717129293824)]} 0 0
   [junit4]   2> 760832 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[7 (1681494717130342400)]} 0 0
   [junit4]   2> 760832 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[7 (1681494717130342400)]} 0 0
   [junit4]   2> 760834 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[8 (1681494717130342401)]} 0 0
   [junit4]   2> 760835 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[8 (1681494717133488128)]} 0 0
   [junit4]   2> 760835 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[9 (1681494717133488128)]} 0 0
   [junit4]   2> 760836 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[9 (1681494717133488129)]} 0 0
   [junit4]   2> 760842 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 760842 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 760851 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 760851 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 760854 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[10 (1681494717151313920)]} 0 3
   [junit4]   2> 760857 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[10 (1681494717153411072)]} 0 2
   [junit4]   2> 760859 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[11 (1681494717156556800)]} 0 0
   [junit4]   2> 760860 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[11 (1681494717158653952)]} 0 1
   [junit4]   2> 760860 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[12 (1681494717159702528)]} 0 0
   [junit4]   2> 760860 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[12 (1681494717159702528)]} 0 0
   [junit4]   2> 760863 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[13 (1681494717162848256)]} 0 0
   [junit4]   2> 760863 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[13 (1681494717162848256)]} 0 0
   [junit4]   2> 760863 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[1001 (1681494717162848257)]} 0 0
   [junit4]   2> 760865 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[1001 (1681494717162848257)]} 0 0
   [junit4]   2> 760865 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[14 (1681494717164945408)]} 0 0
   [junit4]   2> 760866 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[14 (1681494717164945408)]} 0 0
   [junit4]   2> 760866 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[15 (1681494717165993984)]} 0 0
   [junit4]   2> 760866 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[15 (1681494717165993984)]} 0 0
   [junit4]   2> 760866 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[16 (1681494717165993985)]} 0 0
   [junit4]   2> 760866 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[16 (1681494717165993985)]} 0 0
   [junit4]   2> 760866 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[17 (1681494717165993986)]} 0 0
   [junit4]   2> 760866 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[17 (1681494717165993986)]} 0 0
   [junit4]   2> 760866 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[100 (1681494717165993987)]} 0 0
   [junit4]   2> 760871 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[100 (1681494717165993987)]} 0 0
   [junit4]   2> 760871 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[101 (1681494717171236864)]} 0 0
   [junit4]   2> 760871 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[101 (1681494717171236864)]} 0 0
   [junit4]   2> 760871 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[102 (1681494717171236865)]} 0 0
   [junit4]   2> 760871 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[102 (1681494717171236865)]} 0 0
   [junit4]   2> 760873 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[103 (1681494717171236866)]} 0 0
   [junit4]   2> 760873 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[103 (1681494717173334016)]} 0 0
   [junit4]   2> 760873 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[104 (1681494717173334016)]} 0 0
   [junit4]   2> 760874 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[104 (1681494717174382592)]} 0 0
   [junit4]   2> 760874 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[105 (1681494717174382592)]} 0 0
   [junit4]   2> 760874 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[105 (1681494717174382593)]} 0 0
   [junit4]   2> 760874 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[106 (1681494717174382593)]} 0 0
   [junit4]   2> 760874 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[106 (1681494717174382594)]} 0 0
   [junit4]   2> 760874 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[107 (1681494717174382594)]} 0 0
   [junit4]   2> 760874 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[107 (1681494717174382595)]} 0 0
   [junit4]   2> 760878 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[108 (1681494717178576896)]} 0 0
   [junit4]   2> 760878 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[108 (1681494717178576896)]} 0 0
   [junit4]   2> 760878 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[109 (1681494717178576897)]} 0 0
   [junit4]   2> 760878 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[109 (1681494717178576897)]} 0 0
   [junit4]   2> 760878 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[110 (1681494717178576898)]} 0 0
   [junit4]   2> 760881 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[110 (1681494717181722624)]} 0 0
   [junit4]   2> 760881 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[111 (1681494717181722624)]} 0 0
   [junit4]   2> 760882 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[111 (1681494717182771200)]} 0 0
   [junit4]   2> 760882 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[112 (1681494717182771200)]} 0 0
   [junit4]   2> 760883 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[112 (1681494717183819776)]} 0 0
   [junit4]   2> 760883 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[113 (1681494717183819776)]} 0 0
   [junit4]   2> 760883 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[113 (1681494717183819777)]} 0 0
   [junit4]   2> 760884 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[114 (1681494717184868352)]} 0 0
   [junit4]   2> 760884 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[114 (1681494717184868352)]} 0 0
   [junit4]   2> 760884 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[115 (1681494717184868353)]} 0 0
   [junit4]   2> 760885 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[115 (1681494717185916928)]} 0 0
   [junit4]   2> 760885 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[116 (1681494717185916928)]} 0 0
   [junit4]   2> 760886 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[116 (1681494717186965504)]} 0 0
   [junit4]   2> 760886 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[117 (1681494717186965504)]} 0 0
   [junit4]   2> 760887 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[117 (1681494717188014080)]} 0 0
   [junit4]   2> 760887 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[118 (1681494717188014080)]} 0 0
   [junit4]   2> 760888 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[118 (1681494717189062656)]} 0 0
   [junit4]   2> 760888 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[119 (1681494717189062656)]} 0 0
   [junit4]   2> 760889 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[119 (1681494717190111232)]} 0 0
   [junit4]   2> 760889 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[120 (1681494717190111232)]} 0 0
   [junit4]   2> 760889 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[120 (1681494717190111233)]} 0 0
   [junit4]   2> 760890 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[121 (1681494717191159808)]} 0 0
   [junit4]   2> 760890 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[121 (1681494717191159808)]} 0 0
   [junit4]   2> 760890 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[122 (1681494717191159809)]} 0 0
   [junit4]   2> 760891 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[122 (1681494717192208384)]} 0 0
   [junit4]   2> 760891 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[123 (1681494717192208384)]} 0 0
   [junit4]   2> 760891 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[123 (1681494717192208385)]} 0 0
   [junit4]   2> 760892 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[124 (1681494717193256960)]} 0 0
   [junit4]   2> 760892 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[124 (1681494717193256960)]} 0 0
   [junit4]   2> 760893 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[125 (1681494717194305536)]} 0 0
   [junit4]   2> 760893 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[125 (1681494717194305536)]} 0 0
   [junit4]   2> 760894 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[126 (1681494717195354112)]} 0 0
   [junit4]   2> 760894 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[126 (1681494717195354112)]} 0 0
   [junit4]   2> 760895 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[127 (1681494717196402688)]} 0 0
   [junit4]   2> 760895 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[127 (1681494717196402688)]} 0 0
   [junit4]   2> 760895 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[128 (1681494717196402689)]} 0 0
   [junit4]   2> 760896 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[128 (1681494717197451264)]} 0 0
   [junit4]   2> 760896 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[129 (1681494717197451264)]} 0 0
   [junit4]   2> 760896 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[129 (1681494717197451265)]} 0 0
   [junit4]   2> 760896 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[130 (1681494717197451265)]} 0 0
   [junit4]   2> 760898 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[130 (1681494717197451266)]} 0 0
   [junit4]   2> 760898 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[131 (1681494717199548416)]} 0 0
   [junit4]   2> 760898 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[131 (1681494717199548416)]} 0 0
   [junit4]   2> 760899 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[132 (1681494717200596992)]} 0 0
   [junit4]   2> 760899 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[132 (1681494717200596992)]} 0 0
   [junit4]   2> 760900 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[133 (1681494717201645568)]} 0 0
   [junit4]   2> 760900 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[133 (1681494717201645568)]} 0 0
   [junit4]   2> 760900 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[134 (1681494717201645569)]} 0 0
   [junit4]   2> 760901 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[134 (1681494717202694144)]} 0 0
   [junit4]   2> 760902 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[135 (1681494717203742720)]} 0 0
   [junit4]   2> 760902 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[135 (1681494717203742720)]} 0 0
   [junit4]   2> 760902 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[136 (1681494717203742721)]} 0 0
   [junit4]   2> 760903 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[136 (1681494717204791296)]} 0 0
   [junit4]   2> 760903 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[137 (1681494717204791296)]} 0 0
   [junit4]   2> 760903 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[137 (1681494717204791297)]} 0 0
   [junit4]   2> 760904 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[138 (1681494717205839872)]} 0 0
   [junit4]   2> 760904 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[138 (1681494717205839872)]} 0 0
   [junit4]   2> 760905 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[139 (1681494717205839873)]} 0 0
   [junit4]   2> 760905 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[139 (1681494717206888448)]} 0 0
   [junit4]   2> 760906 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[140 (1681494717207937024)]} 0 0
   [junit4]   2> 760906 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[140 (1681494717207937024)]} 0 0
   [junit4]   2> 760907 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[141 (1681494717207937025)]} 0 0
   [junit4]   2> 760907 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[141 (1681494717208985600)]} 0 0
   [junit4]   2> 760907 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[142 (1681494717208985600)]} 0 0
   [junit4]   2> 760908 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[142 (1681494717210034176)]} 0 0
   [junit4]   2> 760908 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[143 (1681494717210034176)]} 0 0
   [junit4]   2> 760909 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[143 (1681494717211082752)]} 0 0
   [junit4]   2> 760909 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[144 (1681494717211082752)]} 0 0
   [junit4]   2> 760909 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[144 (1681494717211082753)]} 0 0
   [junit4]   2> 760910 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[145 (1681494717212131328)]} 0 0
   [junit4]   2> 760911 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[145 (1681494717212131328)]} 0 0
   [junit4]   2> 760911 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[146 (1681494717213179904)]} 0 0
   [junit4]   2> 760911 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[146 (1681494717213179904)]} 0 0
   [junit4]   2> 760912 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[147 (1681494717214228480)]} 0 0
   [junit4]   2> 760912 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[147 (1681494717214228480)]} 0 0
   [junit4]   2> 760913 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[148 (1681494717215277056)]} 0 0
   [junit4]   2> 760913 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[148 (1681494717215277056)]} 0 0
   [junit4]   2> 760914 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[149 (1681494717216325632)]} 0 0
   [junit4]   2> 760914 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={wt=javabin&version=2}{add=[149 (1681494717216325632)]} 0 0
   [junit4]   2> 760924 INFO  
(searcherExecutor-6668-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 760924 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 9
   [junit4]   2> 760933 INFO  
(searcherExecutor-6686-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 760933 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/u/l path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 8
   [junit4]   2> 760935 ERROR (qtp411074358-9730) [    x:collection1 ] 
o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input 
string: "non_numeric_value"
   [junit4]   2>        at 
org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
   [junit4]   2>        at 
org.apache.solr.search.QParser.getSortSpec(QParser.java:271)
   [junit4]   2>        at 
org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:189)
   [junit4]   2>        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:337)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2627)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:814)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:581)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1300)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1215)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
   [junit4]   2>        at 
org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:500)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
   [junit4]   2>        at 
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: java.lang.NumberFormatException: For input string: 
"non_numeric_value"
   [junit4]   2>        at 
java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
   [junit4]   2>        at java.lang.Integer.parseInt(Integer.java:580)
   [junit4]   2>        at java.lang.Integer.valueOf(Integer.java:766)
   [junit4]   2>        at 
org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
   [junit4]   2>        ... 38 more
   [junit4]   2> 
   [junit4]   2> 760935 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*&distrib=false&start=non_numeric_value&wt=javabin&version=2} 
status=400 QTime=0
   [junit4]   2> 760936 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 760936 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 760937 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 760937 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 760938 ERROR (qtp411074358-9731) [    x:collection1 ] 
o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input 
string: "non_numeric_value"
   [junit4]   2>        at 
org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
   [junit4]   2>        at 
org.apache.solr.search.QParser.getSortSpec(QParser.java:274)
   [junit4]   2>        at 
org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:189)
   [junit4]   2>        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:337)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2627)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:814)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:581)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1300)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1215)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
   [junit4]   2>        at 
org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:500)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
   [junit4]   2>        at 
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: java.lang.NumberFormatException: For input string: 
"non_numeric_value"
   [junit4]   2>        at 
java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
   [junit4]   2>        at java.lang.Integer.parseInt(Integer.java:580)
   [junit4]   2>        at java.lang.Integer.valueOf(Integer.java:766)
   [junit4]   2>        at 
org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
   [junit4]   2>        ... 38 more
   [junit4]   2> 
   [junit4]   2> 760938 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*&distrib=false&rows=non_numeric_value&wt=javabin&version=2} 
status=400 QTime=0
   [junit4]   2> 760939 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 760943 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l 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:49265/u/l/collection1|[::1]:6/u/l&rid=127.0.0.1-19936&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598325008&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 760945 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598325008&ids=133,135,146,136,126,6,117,7,128,9&isShard=true&shard.url=http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l&rid=127.0.0.1-19936&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 760946 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l&sort=n_ti1+desc&wt=javabin&version=2}
 rid=127.0.0.1-19936 hits=68 status=0 QTime=9
   [junit4]   2> 760952 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=1
   [junit4]   2> 762999 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l|[::1]:8/u/l&rid=127.0.0.1-19938&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598325021&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 763002 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598325021&ids=143,122,111,14,4,103,16,116,17,106&isShard=true&shard.url=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l|[::1]:8/u/l&rid=127.0.0.1-19938&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 763016 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l|[::1]:8/u/l&sort=n_ti1+asc&wt=javabin&version=2}
 rid=127.0.0.1-19938 hits=68 status=0 QTime=2064
   [junit4]   2> 763016 WARN  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.c.S.SlowRequest slow: [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l|[::1]:8/u/l&sort=n_ti1+asc&wt=javabin&version=2}
 rid=127.0.0.1-19938 hits=68 status=0 QTime=2064
   [junit4]   2> 763030 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 763036 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l 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:49265/u/l/collection1&rid=127.0.0.1-19940&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598327101&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 763038 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598327101&ids=145,123,2,3,14,114,126,116,149,139&isShard=true&shard.url=http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19940&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 763042 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=http://127.0.0.1:49265/u/l/collection1&sort=n_f1+desc&wt=javabin&version=2}
 rid=127.0.0.1-19940 hits=68 status=0 QTime=11
   [junit4]   2> 763042 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 763048 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l&rid=127.0.0.1-19942&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598327115&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 763050 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598327115&ids=121,111,12,101,134,113,104,137,140,141&isShard=true&shard.url=http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l&rid=127.0.0.1-19942&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 763061 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l&sort=n_f1+asc&wt=javabin&version=2}
 rid=127.0.0.1-19942 hits=68 status=0 QTime=13
   [junit4]   2> 763062 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 763065 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19944&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598327131&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 763068 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598327131&ids=11,133,144,122,125,7,117,119,108,142&isShard=true&shard.url=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19944&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 763071 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1&sort=n_tf1+desc&wt=javabin&version=2}
 rid=127.0.0.1-19944 hits=68 status=0 QTime=7
   [junit4]   2> 763072 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 765093 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[::1]:8/u/l|http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19946&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598327141&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 765094 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598327141&ids=143,1,146,113,115,137,105,138,10,120&isShard=true&shard.url=[::1]:8/u/l|http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19946&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 765095 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:8/u/l|http://127.0.0.1:49265/u/l/collection1&sort=n_tf1+asc&wt=javabin&version=2}
 rid=127.0.0.1-19946 hits=68 status=0 QTime=2022
   [junit4]   2> 765095 WARN  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.c.S.SlowRequest slow: [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:8/u/l|http://127.0.0.1:49265/u/l/collection1&sort=n_tf1+asc&wt=javabin&version=2}
 rid=127.0.0.1-19946 hits=68 status=0 QTime=2022
   [junit4]   2> 765107 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 767137 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[::1]:8/u/l|[::1]:6/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:4/u/l&rid=127.0.0.1-19948&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598329176&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 767139 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598329176&ids=110,123,101,3,15,114,137,106,119,109&isShard=true&shard.url=[::1]:8/u/l|[::1]:6/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:4/u/l&rid=127.0.0.1-19948&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 767140 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:8/u/l|[::1]:6/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:4/u/l&sort=n_d1+desc&wt=javabin&version=2}
 rid=127.0.0.1-19948 hits=68 status=0 QTime=2032
   [junit4]   2> 767140 WARN  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.c.S.SlowRequest slow: [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:8/u/l|[::1]:6/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:4/u/l&sort=n_d1+desc&wt=javabin&version=2}
 rid=127.0.0.1-19948 hits=68 status=0 QTime=2032
   [junit4]   2> 767153 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 767155 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:49265/u/l/collection1|[::1]:8/u/l|[::1]:4/u/l|[::1]:6/u/l&rid=127.0.0.1-19950&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598331222&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 767157 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598331222&ids=143,12,13,4,105,6,118,8,141,131&isShard=true&shard.url=http://127.0.0.1:49265/u/l/collection1|[::1]:8/u/l|[::1]:4/u/l|[::1]:6/u/l&rid=127.0.0.1-19950&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 767157 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=http://127.0.0.1:49265/u/l/collection1|[::1]:8/u/l|[::1]:4/u/l|[::1]:6/u/l&sort=n_d1+asc&wt=javabin&version=2}
 rid=127.0.0.1-19950 hits=68 status=0 QTime=3
   [junit4]   2> 767158 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 767159 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[::1]:6/u/l|[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19952&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598331227&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 767160 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598331227&ids=110,123,14,136,103,125,149,6,7,10&isShard=true&shard.url=[::1]:6/u/l|[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19952&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 767160 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:6/u/l|[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1&sort=n_td1+desc&wt=javabin&version=2}
 rid=127.0.0.1-19952 hits=68 status=0 QTime=2
   [junit4]   2> 767162 INFO  (qtp411074358-9729) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 767163 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19954&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598331230&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 767164 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598331230&ids=1,144,12,112,134,113,115,116,140,130&isShard=true&shard.url=http://127.0.0.1:49265/u/l/collection1&rid=127.0.0.1-19954&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 767165 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=http://127.0.0.1:49265/u/l/collection1&sort=n_td1+asc&wt=javabin&version=2}
 rid=127.0.0.1-19954 hits=68 status=0 QTime=2
   [junit4]   2> 767166 INFO  (qtp411074358-9728) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 767167 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:8/u/l&rid=127.0.0.1-19956&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598331234&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 767168 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598331234&ids=110,121,111,1,3,4,125,16,138,127&isShard=true&shard.url=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:8/u/l&rid=127.0.0.1-19956&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 767169 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:8/u/l&sort=n_l1+desc&wt=javabin&version=2}
 rid=127.0.0.1-19956 hits=68 status=0 QTime=2
   [junit4]   2> 767169 INFO  (qtp411074358-9731) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 767170 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[::1]:8/u/l|[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l&rid=127.0.0.1-19958&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598331238&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 767172 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598331238&ids=144,146,149,106,129,119,108,130,141,120&isShard=true&shard.url=[::1]:8/u/l|[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l&rid=127.0.0.1-19958&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 767172 INFO  (qtp1485766835-9749) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=[::1]:8/u/l|[::1]:4/u/l|http://127.0.0.1:49265/u/l/collection1|[::1]:6/u/l&sort=n_l1+asc&wt=javabin&version=2}
 rid=127.0.0.1-19958 hits=68 status=0 QTime=2
   [junit4]   2> 767173 INFO  (qtp411074358-9730) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 767174 INFO  (qtp1485766835-9750) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:49265/u/l/collection1|[::1]:4/u/l&rid=127.0.0.1-19960&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603598331241&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 767175 INFO  (qtp1485766835-9752) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603598331241&ids=133,123,134,136,149,138,106,117,120,131&isShard=true&shard.url=http://127.0.0.1:49265/u/l/collection1|[::1]:4/u/l&rid=127.0.0.1-19960&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 767175 INFO  (qtp1485766835-9748) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/u/l path=/select 
params={q=*:*&shards=http://127.0.0.1:49265/u/l/collection1|[::1]:4/u/l&sort=n_tl1+desc&wt=javabin&version=2}
 rid=127.0.0.1-19960 hits=68 status=0 QTime=1
   [junit4]   2> 767176 INFO 

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

che.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 878514 INFO  (closeThreadPool-6868-thread-8) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@5fff1073{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 878516 INFO  (closeThreadPool-6868-thread-8) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@76e23c5{/u/l,null,UNAVAILABLE}
   [junit4]   2> 878516 INFO  (closeThreadPool-6868-thread-8) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=733FCD7EFE6D2E04 -Dtests.slow=true -Dtests.locale=sq 
-Dtests.timezone=America/Denver -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR    121s J1 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=10051, name=httpShardExecutor-6828-thread-10, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]
   [junit4]    > Caused by: java.util.concurrent.RejectedExecutionException: 
Task 
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@4df9b287 
rejected from 
java.util.concurrent.ScheduledThreadPoolExecutor@46c4ca3b[Terminated, pool size 
= 1, active threads = 0, queued tasks = 0, completed tasks = 0]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([733FCD7EFE6D2E04]:0)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
   [junit4]    >        at 
java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
   [junit4]    >        at 
java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
   [junit4]    >        at 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
   [junit4]    >        at 
org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
   [junit4]    >        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)Throwable #2: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=10052, name=httpShardExecutor-6828-thread-11, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]
   [junit4]    > Caused by: java.util.concurrent.RejectedExecutionException: 
Task 
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@48033d4b 
rejected from 
java.util.concurrent.ScheduledThreadPoolExecutor@46c4ca3b[Terminated, pool size 
= 1, active threads = 0, queued tasks = 0, completed tasks = 0]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([733FCD7EFE6D2E04]:0)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
   [junit4]    >        at 
java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
   [junit4]    >        at 
java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
   [junit4]    >        at 
org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
   [junit4]    >        at 
org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
   [junit4]    >        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 878522 INFO  
(SUITE-TestDistributedSearch-seed#[733FCD7EFE6D2E04]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87): 
{severity=PostingsFormat(name=LuceneVarGapFixedInterval), 
foo_sev_enum=PostingsFormat(name=LuceneVarGapFixedInterval), 
SubjectTerms_mfacet=PostingsFormat(name=LuceneFixedGap), 
multiDefault=PostingsFormat(name=MockRandom), 
_root_=PostingsFormat(name=LuceneFixedGap), rnd_b=Lucene84, 
a_s=PostingsFormat(name=LuceneVarGapFixedInterval), oddField_s=Lucene84, 
lowerfilt1and2=PostingsFormat(name=MockRandom), 
a_t=PostingsFormat(name=MockRandom), 
foo_b=PostingsFormat(name=LuceneVarGapFixedInterval), 
lowerfilt=PostingsFormat(name=MockRandom), id=Lucene84, 
text=PostingsFormat(name=LuceneVarGapFixedInterval)}, 
docValues:{other_tl1=DocValuesFormat(name=Lucene80), 
range_facet_l_dv=DocValuesFormat(name=Direct), 
n_l1=DocValuesFormat(name=Asserting), a_n_tdt=DocValuesFormat(name=Lucene80), 
intDefault=DocValuesFormat(name=Lucene80), 
n_td1=DocValuesFormat(name=Lucene80), n_d1=DocValuesFormat(name=Asserting), 
range_facet_l=DocValuesFormat(name=Asserting), 
n_f1=DocValuesFormat(name=Lucene80), n_tl1=DocValuesFormat(name=Lucene80), 
n_tf1=DocValuesFormat(name=Asserting), 
intDvoDefault=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Asserting), 
severity=DocValuesFormat(name=Lucene80), 
foo_sev_enum=DocValuesFormat(name=Lucene80), 
n_dt1=DocValuesFormat(name=Lucene80), b_n_tdt=DocValuesFormat(name=Lucene80), 
n_ti1=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene80), 
n_tdt1=DocValuesFormat(name=Lucene80), id_i1=DocValuesFormat(name=Lucene80), 
foo_d=DocValuesFormat(name=Direct), 
range_facet_i_dv=DocValuesFormat(name=Asserting), 
foo_f=DocValuesFormat(name=Lucene80), a_i_p=DocValuesFormat(name=Lucene80)}, 
maxPointsInLeafNode=1927, maxMBSortInHeap=7.020537208778441, 
sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=sq, 
timezone=America/Denver
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_201 
(64-bit)/cpus=6,threads=2,free=238582808,total=422051840
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestSubQueryTransformerCrossCore, DistributedQueueTest, TestStressRecovery, 
RecoveryAfterSoftCommitTest, DistributedFacetSimpleRefinementLongTailTest, 
RoutingToNodesWithPropertiesTest, OverseerStatusTest, MetricsConfigTest, 
SolrTestCaseJ4Test, ClusterStateMockUtilTest, TestManagedSchemaAPI, 
HdfsUnloadDistributedZkTest, TestFieldSortValues, TestUtils, 
TestSolrQueryResponse, TestImplicitCoreProperties, TestCloudJSONFacetSKGEquiv, 
TestSimExtremeIndexing, TestJsonFacetsWithNestedObjects, 
BasicDistributedZk2Test, BlockJoinFacetRandomTest, 
NodeLostTriggerIntegrationTest, BitVectorTest, CollectionTooManyReplicasTest, 
SystemInfoHandlerTest, ChangedSchemaMergeTest, XmlUpdateRequestHandlerTest, 
RegexBytesRefFilterTest, BaseCdcrDistributedZkTest, TestShortCircuitedRequests, 
TestReRankQParserPlugin, SearchHandlerTest, TestLuceneIndexBackCompat, 
TestRandomDVFaceting, TestDFISimilarityFactory, SolrMetricsIntegrationTest, 
TestTolerantSearch, HdfsRecoveryZkTest, SolrCloudReportersTest, 
TestLegacyBM25SimilarityFactory, PrimUtilsTest, DistanceUnitsTest, 
TestReqParamsAPI, TestSchemaSimilarityResource, SoftAutoCommitTest, 
RankFieldTest, ExecutePlanActionTest, DistributedFacetPivotWhiteBoxTest, 
CustomHighlightComponentTest, ThreadDumpHandlerTest, BasicFunctionalityTest, 
TestRandomRequestDistribution, TestQueryTypes, TestSchemaManager, 
TestCloudDeleteByQuery, TestDocTermOrds, MissingSegmentRecoveryTest, 
TestCorePropertiesReload, DeleteShardTest, MoreLikeThisComponentTest, 
TestSimClusterStateProvider, TestSolrCoreSnapshots, 
SignificantTermsQParserPluginTest, TestFieldCacheSortRandom, 
FieldAnalysisRequestHandlerTest, TestShardHandlerFactory, 
TestSlowCompositeReaderWrapper, CursorMarkTest, TransactionLogTest, 
TestSolrConfigHandlerConcurrent, SplitShardTest, ZkStateReaderTest, 
SuggesterTest, ReplaceNodeTest, TestSimPolicyCloud, TestSolrJ, 
TestSafeXMLParsing, TestFieldResource, SpatialRPTFieldTypeTest, 
TestNoOpRegenerator, SpellingQueryConverterTest, TestAtomicUpdateErrorCases, 
TestDistributedSearch]
   [junit4] Completed [329/921 (1!)] on J1 in 121.75s, 1 test, 1 error <<< 
FAILURES!

[...truncated 54354 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1753/consoleText

[repro] Revision: b6d06bb309d121901ab6ce1d1935b4067ce610fe

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"

[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr\core
[repro]       TestDistributedSearch
[repro] ant compile-test

[...truncated 3472 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror 
"-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" 
-Dtests.seed=733FCD7EFE6D2E04 -Dtests.slow=true -Dtests.locale=sq 
-Dtests.timezone=America/Denver -Dtests.asserts=true -Dtests.file.encoding=UTF-8

[...truncated 92 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: org.apache.solr.TestDistributedSearch
[repro] Exiting with code 0

[...truncated 99 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to