[JENKINS-Experimental-GC] Lucene-Solr-8.x-Windows (64bit/jdk-14.0.1) - Build # 1705 - Unstable!

Policeman Jenkins Server Wed, 07 Oct 2020 11:03:24 -0700

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1705/
Java: 64bit/jdk-14.0.1 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions 
-XX:+UseShenandoahGC


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

Error Message:
Captured an uncaught exception in thread: Thread[id=17284, 
name=httpShardExecutor-11348-thread-5, state=RUNNABLE, 
group=TGRP-TestDistributedSearch]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=17284, name=httpShardExecutor-11348-thread-5, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]
        at 
__randomizedtesting.SeedInfo.seed([C40D6521BB10F747:4C595AFB15EC9ABF]:0)
Caused by: java.util.concurrent.RejectedExecutionException: Task 
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@63996e3b[Not
 completed, task = 
java.util.concurrent.Executors$RunnableAdapter@3a5271f3[Wrapped task = 
org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$528/0x0000000801099440@2fe9a7d8]]
 rejected from 
java.util.concurrent.ScheduledThreadPoolExecutor@63a4d601[Terminated, pool size 
= 0, active threads = 0, queued tasks = 0, completed tasks = 0]
        at __randomizedtesting.SeedInfo.seed([C40D6521BB10F747]:0)
        at 
java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2057)
        at 
java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:827)
        at 
java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
        at 
java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
        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.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
        at 
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
        at java.base/java.lang.Thread.run(Thread.java:832)




Build Log:
[...truncated 15275 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> 1374809 INFO  
(SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-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> 1374809 INFO  
(SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1374809 INFO  
(SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-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_C40D6521BB10F747-001\data-dir-91-001
   [junit4]   2> 1374809 WARN  
(SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1374809 INFO  
(SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1374815 INFO  
(SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-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> 1374815 INFO  
(SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [     ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1375457 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
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_C40D6521BB10F747-001\tempDir-001\control\cores\collection1
   [junit4]   2> 1375469 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1375469 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1375469 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1375469 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
   [junit4]   2> 1375470 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1375470 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1375470 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1375471 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7101d167{/,null,AVAILABLE}
   [junit4]   2> 1375471 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@dc75af8{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:59568}
   [junit4]   2> 1375471 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.Server Started @1375508ms
   [junit4]   2> 1375471 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=59568, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\control\cores}
   [junit4]   2> 1375473 ERROR 
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1375473 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1375473 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.7.0
   [junit4]   2> 1375473 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1375473 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 1375473 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-10-07T17:15:38.766756900Z
   [junit4]   2> 1375473 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
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_C40D6521BB10F747-001\tempDir-001\control\solr.xml
   [junit4]   2> 1375479 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1375479 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1375480 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1375607 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1375609 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@45151e65[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1375609 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@45151e65[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1375613 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4b6edb9b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1375613 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4b6edb9b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1375615 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
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> 1375634 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1375634 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 1375659 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
   [junit4]   2> 1375669 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
   [junit4]   2> 1375669 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
   [junit4]   2> 1375672 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
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_C40D6521BB10F747-001\tempDir-001\control\cores
   [junit4]   2> 1375672 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1375689 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 1375718 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 1375849 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field 
id
   [junit4]   2> 1375869 INFO  (coreLoadExecutor-11204-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> 1375869 INFO  (coreLoadExecutor-11204-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@3849b915
   [junit4]   2> 1375869 INFO  (coreLoadExecutor-11204-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_C40D6521BB10F747-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_C40D6521BB10F747-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 1375873 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1375985 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1375985 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1375988 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1375988 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1375988 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.26288852325861034]
   [junit4]   2> 1375989 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1376000 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1376058 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 1376058 INFO  (coreLoadExecutor-11204-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1679914108820914176
   [junit4]   2> 1376060 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1376060 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1376060 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1376060 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1376060 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1376060 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1376062 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1376064 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1376064 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1376065 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1376889 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
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_C40D6521BB10F747-001\tempDir-001\shard0\cores\collection1
   [junit4]   2> 1376895 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1376895 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1376895 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1376895 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
   [junit4]   2> 1376895 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1376895 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1376895 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1376895 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@db0ed63{/,null,AVAILABLE}
   [junit4]   2> 1376899 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@3fe2e8fe{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:59637}
   [junit4]   2> 1376899 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.s.Server Started @1376934ms
   [junit4]   2> 1376899 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=59637, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\shard0\cores}
   [junit4]   2> 1376899 ERROR 
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1376899 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1376899 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.7.0
   [junit4]   2> 1376899 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1376899 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 1376899 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-10-07T17:15:40.192810400Z
   [junit4]   2> 1376900 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
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_C40D6521BB10F747-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 1376903 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1376905 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1376906 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1377125 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1377126 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4eaba141[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1377126 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4eaba141[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1377129 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@ecdcd5c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1377129 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@ecdcd5c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1377130 WARN  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
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> 1377147 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1377147 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 1377165 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
   [junit4]   2> 1377174 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
   [junit4]   2> 1377175 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
   [junit4]   2> 1377177 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
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_C40D6521BB10F747-001\tempDir-001\shard0\cores
   [junit4]   2> 1377177 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1377187 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 1377213 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 1377329 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field 
id
   [junit4]   2> 1377354 INFO  (coreLoadExecutor-11222-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> 1377356 INFO  (coreLoadExecutor-11222-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@3849b915
   [junit4]   2> 1377356 INFO  (coreLoadExecutor-11222-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_C40D6521BB10F747-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_C40D6521BB10F747-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 1377358 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1377526 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1377527 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1377529 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1377529 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1377590 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.26288852325861034]
   [junit4]   2> 1377639 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1377657 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1377726 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 1377727 INFO  (coreLoadExecutor-11222-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1679914110570987520
   [junit4]   2> 1377730 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1377730 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1377730 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1377730 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1377730 INFO  
(TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1377732 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1377732 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1377732 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1377732 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1377732 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1377732 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1377737 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1679914110576230400)} 0 3
   [junit4]   2> 1377762 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1679914110583570432)} 0 21
   [junit4]   2> 1377766 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1679914110608736256)]} 0 3
   [junit4]   2> 1377769 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1679914110611881984)]} 0 2
   [junit4]   2> 1377771 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1679914110616076288)]} 0 1
   [junit4]   2> 1377773 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1679914110617124864)]} 0 0
   [junit4]   2> 1377773 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1679914110619222016)]} 0 0
   [junit4]   2> 1377773 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1679914110619222016)]} 0 0
   [junit4]   2> 1377778 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1679914110619222017)]} 0 1
   [junit4]   2> 1377778 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1679914110624464896)]} 0 0
   [junit4]   2> 1377780 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1679914110624464896)]} 0 0
   [junit4]   2> 1377780 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1679914110626562048)]} 0 0
   [junit4]   2> 1377780 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1679914110626562048)]} 0 0
   [junit4]   2> 1377781 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1679914110627610624)]} 0 0
   [junit4]   2> 1377781 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1679914110627610624)]} 0 0
   [junit4]   2> 1377781 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1679914110627610625)]} 0 0
   [junit4]   2> 1377781 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1679914110627610625)]} 0 0
   [junit4]   2> 1377781 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1679914110627610626)]} 0 0
   [junit4]   2> 1377781 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1679914110627610626)]} 0 0
   [junit4]   2> 1377781 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1679914110627610627)]} 0 0
   [junit4]   2> 1377795 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1377796 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 11
   [junit4]   2> 1377803 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1377803 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 1377808 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1679914110651727872)]} 0 4
   [junit4]   2> 1377811 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1679914110658019328)]} 0 2
   [junit4]   2> 1377813 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1679914110661165056)]} 0 0
   [junit4]   2> 1377820 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1679914110665359360)]} 0 4
   [junit4]   2> 1377823 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1679914110669553664)]} 0 1
   [junit4]   2> 1377824 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1679914110672699392)]} 0 0
   [junit4]   2> 1377825 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1679914110673747968)]} 0 0
   [junit4]   2> 1377827 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1679914110675845120)]} 0 0
   [junit4]   2> 1377829 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1679914110676893696)]} 0 0
   [junit4]   2> 1377830 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1679914110678990848)]} 0 0
   [junit4]   2> 1377832 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1679914110681088000)]} 0 0
   [junit4]   2> 1377833 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1679914110682136576)]} 0 0
   [junit4]   2> 1377833 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1679914110682136576)]} 0 0
   [junit4]   2> 1377833 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1679914110682136577)]} 0 0
   [junit4]   2> 1377833 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1679914110682136577)]} 0 0
   [junit4]   2> 1377836 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1679914110682136578)]} 0 0
   [junit4]   2> 1377837 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1679914110685282304)]} 0 0
   [junit4]   2> 1377837 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1679914110686330880)]} 0 0
   [junit4]   2> 1377837 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1679914110686330880)]} 0 0
   [junit4]   2> 1377841 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1679914110690525184)]} 0 0
   [junit4]   2> 1377842 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1679914110691573760)]} 0 0
   [junit4]   2> 1377842 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1679914110691573760)]} 0 0
   [junit4]   2> 1377843 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1679914110692622336)]} 0 0
   [junit4]   2> 1377845 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1679914110692622336)]} 0 0
   [junit4]   2> 1377846 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1679914110695768064)]} 0 0
   [junit4]   2> 1377846 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1679914110695768064)]} 0 0
   [junit4]   2> 1377849 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1679914110698913792)]} 0 0
   [junit4]   2> 1377850 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1679914110699962368)]} 0 0
   [junit4]   2> 1377850 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1679914110699962368)]} 0 0
   [junit4]   2> 1377853 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1679914110699962369)]} 0 0
   [junit4]   2> 1377853 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1679914110703108096)]} 0 0
   [junit4]   2> 1377853 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1679914110703108096)]} 0 0
   [junit4]   2> 1377853 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1679914110703108097)]} 0 0
   [junit4]   2> 1377857 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1679914110707302400)]} 0 0
   [junit4]   2> 1377859 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1679914110709399552)]} 0 0
   [junit4]   2> 1377860 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1679914110709399552)]} 0 0
   [junit4]   2> 1377861 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1679914110710448128)]} 0 0
   [junit4]   2> 1377861 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1679914110711496704)]} 0 0
   [junit4]   2> 1377862 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1679914110712545280)]} 0 0
   [junit4]   2> 1377863 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1679914110713593856)]} 0 0
   [junit4]   2> 1377864 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1679914110714642432)]} 0 0
   [junit4]   2> 1377864 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1679914110714642432)]} 0 0
   [junit4]   2> 1377864 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1679914110714642433)]} 0 0
   [junit4]   2> 1377867 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1679914110717788160)]} 0 0
   [junit4]   2> 1377869 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1679914110719885312)]} 0 0
   [junit4]   2> 1377869 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1679914110719885312)]} 0 0
   [junit4]   2> 1377873 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1679914110724079616)]} 0 0
   [junit4]   2> 1377873 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1679914110724079616)]} 0 0
   [junit4]   2> 1377873 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1679914110724079617)]} 0 0
   [junit4]   2> 1377878 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1679914110729322496)]} 0 0
   [junit4]   2> 1377878 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1679914110729322496)]} 0 0
   [junit4]   2> 1377878 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1679914110729322497)]} 0 0
   [junit4]   2> 1377881 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1679914110729322497)]} 0 0
   [junit4]   2> 1377881 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1679914110732468224)]} 0 0
   [junit4]   2> 1377881 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1679914110732468224)]} 0 0
   [junit4]   2> 1377881 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1679914110732468225)]} 0 0
   [junit4]   2> 1377885 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1679914110732468225)]} 0 0
   [junit4]   2> 1377885 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1679914110736662528)]} 0 0
   [junit4]   2> 1377885 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1679914110736662528)]} 0 0
   [junit4]   2> 1377885 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1679914110736662529)]} 0 0
   [junit4]   2> 1377889 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1679914110740856832)]} 0 0
   [junit4]   2> 1377889 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1679914110740856832)]} 0 0
   [junit4]   2> 1377889 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1679914110740856833)]} 0 0
   [junit4]   2> 1377889 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1679914110740856833)]} 0 0
   [junit4]   2> 1377892 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1679914110742953984)]} 0 0
   [junit4]   2> 1377893 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1679914110745051136)]} 0 0
   [junit4]   2> 1377893 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1679914110745051136)]} 0 0
   [junit4]   2> 1377893 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1679914110745051137)]} 0 0
   [junit4]   2> 1377897 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1679914110745051137)]} 0 0
   [junit4]   2> 1377897 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1679914110749245440)]} 0 0
   [junit4]   2> 1377897 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1679914110749245440)]} 0 0
   [junit4]   2> 1377900 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1679914110752391168)]} 0 0
   [junit4]   2> 1377900 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1679914110752391168)]} 0 0
   [junit4]   2> 1377900 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1679914110752391169)]} 0 0
   [junit4]   2> 1377901 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1679914110753439744)]} 0 0
   [junit4]   2> 1377901 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1679914110753439744)]} 0 0
   [junit4]   2> 1377903 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1679914110755536896)]} 0 0
   [junit4]   2> 1377904 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1679914110756585472)]} 0 0
   [junit4]   2> 1377905 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1679914110757634048)]} 0 0
   [junit4]   2> 1377906 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1679914110758682624)]} 0 0
   [junit4]   2> 1377907 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1679914110759731200)]} 0 0
   [junit4]   2> 1377909 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1679914110760779776)]} 0 0
   [junit4]   2> 1377909 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1679914110761828352)]} 0 0
   [junit4]   2> 1377911 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1679914110763925504)]} 0 0
   [junit4]   2> 1377912 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1679914110764974080)]} 0 0
   [junit4]   2> 1377912 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1679914110764974080)]} 0 0
   [junit4]   2> 1377914 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1679914110766022656)]} 0 0
   [junit4]   2> 1377915 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1679914110768119808)]} 0 0
   [junit4]   2> 1377916 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1679914110768119808)]} 0 0
   [junit4]   2> 1377916 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1679914110769168384)]} 0 0
   [junit4]   2> 1377917 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1679914110770216960)]} 0 0
   [junit4]   2> 1377918 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1679914110771265536)]} 0 0
   [junit4]   2> 1377919 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1679914110772314112)]} 0 0
   [junit4]   2> 1377919 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1679914110772314112)]} 0 0
   [junit4]   2> 1377920 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1679914110773362688)]} 0 0
   [junit4]   2> 1377921 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1679914110774411264)]} 0 0
   [junit4]   2> 1377922 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1679914110774411264)]} 0 0
   [junit4]   2> 1377923 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1679914110776508416)]} 0 0
   [junit4]   2> 1377924 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1679914110777556992)]} 0 0
   [junit4]   2> 1377925 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1679914110778605568)]} 0 0
   [junit4]   2> 1377925 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1679914110778605568)]} 0 0
   [junit4]   2> 1377925 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1679914110778605569)]} 0 0
   [junit4]   2> 1377928 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1679914110778605569)]} 0 0
   [junit4]   2> 1377928 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1679914110781751296)]} 0 0
   [junit4]   2> 1377929 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1679914110782799872)]} 0 0
   [junit4]   2> 1377929 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1679914110782799872)]} 0 0
   [junit4]   2> 1377929 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1679914110782799873)]} 0 0
   [junit4]   2> 1377929 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1679914110782799873)]} 0 0
   [junit4]   2> 1377932 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1679914110785945600)]} 0 0
   [junit4]   2> 1377933 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1679914110786994176)]} 0 0
   [junit4]   2> 1377934 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1679914110788042752)]} 0 0
   [junit4]   2> 1377935 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1679914110789091328)]} 0 0
   [junit4]   2> 1377936 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1679914110790139904)]} 0 0
   [junit4]   2> 1377937 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1679914110791188480)]} 0 0
   [junit4]   2> 1377938 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1679914110792237056)]} 0 0
   [junit4]   2> 1377940 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1679914110794334208)]} 0 0
   [junit4]   2> 1377941 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1679914110795382784)]} 0 0
   [junit4]   2> 1377942 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1679914110796431360)]} 0 0
   [junit4]   2> 1377955 INFO  
(searcherExecutor-11206-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1377955 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 11
   [junit4]   2> 1377962 INFO  
(searcherExecutor-11224-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1377962 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 8
   [junit4]   2> 1377967 ERROR (qtp132354293-17031) [    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:309)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2612)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:815)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:588)
   [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.base/java.lang.Thread.run(Thread.java:832)
   [junit4]   2> Caused by: java.lang.NumberFormatException: For input string: 
"non_numeric_value"
   [junit4]   2>        at 
java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:68)
   [junit4]   2>        at 
java.base/java.lang.Integer.parseInt(Integer.java:652)
   [junit4]   2>        at java.base/java.lang.Integer.valueOf(Integer.java:983)
   [junit4]   2>        at 
org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
   [junit4]   2>        ... 38 more
   [junit4]   2> 
   [junit4]   2> 1377967 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=non_numeric_value&wt=javabin&version=2} 
status=400 QTime=0
   [junit4]   2> 1377969 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1377969 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 1377971 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1377971 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 1377973 ERROR (qtp132354293-17030) [    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:309)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2612)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:815)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:588)
   [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.base/java.lang.Thread.run(Thread.java:832)
   [junit4]   2> Caused by: java.lang.NumberFormatException: For input string: 
"non_numeric_value"
   [junit4]   2>        at 
java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:68)
   [junit4]   2>        at 
java.base/java.lang.Integer.parseInt(Integer.java:652)
   [junit4]   2>        at java.base/java.lang.Integer.valueOf(Integer.java:983)
   [junit4]   2>        at 
org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
   [junit4]   2>        ... 38 more
   [junit4]   2> 
   [junit4]   2> 1377973 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&rows=non_numeric_value&wt=javabin&version=2} 
status=400 QTime=0
   [junit4]   2> 1377975 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1380007 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[::1]:6/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28308&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090941272&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1380009 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090941272&ids=143,122,133,102,5,16,105,117,106,142&isShard=true&shard.url=[::1]:6/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28308&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1380011 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[::1]:6/|http://127.0.0.1:59637/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 rid=127.0.0.1-28308 hits=68 status=0 QTime=2032
   [junit4]   2> 1380011 WARN  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.c.S.SlowRequest slow: [collection1]  webapp= path=/select 
params={q=*:*&shards=[::1]:6/|http://127.0.0.1:59637/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 rid=127.0.0.1-28308 hits=68 status=0 QTime=2032
   [junit4]   2> 1380013 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1380015 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[::1]:6/|http://127.0.0.1:59637/collection1|[::1]:8/&rid=127.0.0.1-28310&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943308&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1380017 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943308&ids=110,144,111,145,14,126,6,118,109,10&isShard=true&shard.url=[::1]:6/|http://127.0.0.1:59637/collection1|[::1]:8/&rid=127.0.0.1-28310&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1380018 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[::1]:6/|http://127.0.0.1:59637/collection1|[::1]:8/&sort=n_ti1+asc&wt=javabin&version=2}
 rid=127.0.0.1-28310 hits=68 status=0 QTime=4
   [junit4]   2> 1380019 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1380022 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28312&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943313&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1380022 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943313&ids=11,100,102,103,147,17,106,7,107,8&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28312&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1380023 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_f1+desc&wt=javabin&version=2}
 rid=127.0.0.1-28312 hits=68 status=0 QTime=2
   [junit4]   2> 1380024 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1380026 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28314&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943317&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1380027 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943317&ids=110,144,101,124,135,16,138,127,109,130&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28314&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1380029 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_f1+asc&wt=javabin&version=2}
 rid=127.0.0.1-28314 hits=68 status=0 QTime=4
   [junit4]   2> 1380031 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1380033 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:8/&rid=127.0.0.1-28316&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943325&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1380033 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943325&ids=100,1,13,125,5,1001,118,108,109,130&isShard=true&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:8/&rid=127.0.0.1-28316&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1380034 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:8/&sort=n_tf1+desc&wt=javabin&version=2}
 rid=127.0.0.1-28316 hits=68 status=0 QTime=2
   [junit4]   2> 1380035 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384106 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[::1]:8/|[::1]:4/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28318&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943329&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384108 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943329&ids=12,144,102,135,104,116,149,7,106,129&isShard=true&shard.url=[::1]:8/|[::1]:4/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28318&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1384110 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[::1]:8/|[::1]:4/|http://127.0.0.1:59637/collection1&sort=n_tf1+asc&wt=javabin&version=2}
 rid=127.0.0.1-28318 hits=68 status=0 QTime=4074
   [junit4]   2> 1384111 WARN  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.c.S.SlowRequest slow: [collection1]  webapp= path=/select 
params={q=*:*&shards=[::1]:8/|[::1]:4/|http://127.0.0.1:59637/collection1&sort=n_tf1+asc&wt=javabin&version=2}
 rid=127.0.0.1-28318 hits=68 status=0 QTime=4074
   [junit4]   2> 1384113 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384116 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[::1]:4/|http://127.0.0.1:59637/collection1|[::1]:6/&rid=127.0.0.1-28320&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947406&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384118 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947406&ids=143,100,102,14,126,149,139,141,130,10&isShard=true&shard.url=[::1]:4/|http://127.0.0.1:59637/collection1|[::1]:6/&rid=127.0.0.1-28320&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1384119 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[::1]:4/|http://127.0.0.1:59637/collection1|[::1]:6/&sort=n_d1+desc&wt=javabin&version=2}
 rid=127.0.0.1-28320 hits=68 status=0 QTime=4
   [junit4]   2> 1384121 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384123 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28322&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947414&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384125 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947414&ids=122,111,13,136,16,127,138,128,117,1001&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28322&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1384125 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_d1+asc&wt=javabin&version=2}
 rid=127.0.0.1-28322 hits=68 status=0 QTime=4
   [junit4]   2> 1384127 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384129 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:59637/collection1|[::1]:4/&rid=127.0.0.1-28324&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947420&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384130 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947420&ids=1,111,113,102,126,7,129,8,118,108&isShard=true&shard.url=http://127.0.0.1:59637/collection1|[::1]:4/&rid=127.0.0.1-28324&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1384132 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1|[::1]:4/&sort=n_td1+desc&wt=javabin&version=2}
 rid=127.0.0.1-28324 hits=68 status=0 QTime=3
   [junit4]   2> 1384133 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384135 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28326&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947427&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384137 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947427&ids=132,11,133,145,146,103,6,117,1001,10&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28326&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1384138 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_td1+asc&wt=javabin&version=2}
 rid=127.0.0.1-28326 hits=68 status=0 QTime=4
   [junit4]   2> 1384140 INFO  (qtp132354293-17031) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384142 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28328&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947434&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384143 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947434&ids=143,111,123,113,102,116,6,128,118,9&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28328&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1384145 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_l1+desc&wt=javabin&version=2}
 rid=127.0.0.1-28328 hits=68 status=0 QTime=3
   [junit4]   2> 1384145 INFO  (qtp132354293-17032) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384148 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/&rid=127.0.0.1-28330&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947440&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384149 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947440&ids=110,134,124,148,17,105,138,139,140,120&isShard=true&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/&rid=127.0.0.1-28330&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1384150 INFO  (qtp1058486504-17053) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1|[::1]:6/&sort=n_l1+asc&wt=javabin&version=2}
 rid=127.0.0.1-28330 hits=68 status=0 QTime=3
   [junit4]   2> 1384151 INFO  (qtp132354293-17030) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384153 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:4/&rid=127.0.0.1-28332&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947445&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384154 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947445&ids=121,11,1,123,13,2,3,126,148,119&isShard=true&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:4/&rid=127.0.0.1-28332&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1384156 INFO  (qtp1058486504-17051) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:4/&sort=n_tl1+desc&wt=javabin&version=2}
 rid=127.0.0.1-28332 hits=68 status=0 QTime=4
   [junit4]   2> 1384158 INFO  (qtp132354293-17033) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1384160 INFO  (qtp1058486504-17050) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[::1]:4/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28334&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947452&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1384161 INFO  (qtp1058486504-17054) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&df=text

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

eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
   [junit4]   2>        at 
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]   2>        at 
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
   [junit4]   2>        at java.base/java.lang.Thread.run(Thread.java:832)
   [junit4]   2> 
   [junit4]   2> 1512353 INFO  (closeThreadPool-11406-thread-6) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@524cdd6d{/,null,UNAVAILABLE}
   [junit4]   2> 1512354 INFO  (closeThreadPool-11406-thread-6) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1512355 INFO  (closeThreadPool-11406-thread-8) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@573370d1{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1512357 INFO  (closeThreadPool-11406-thread-8) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3edfa643{/,null,UNAVAILABLE}
   [junit4]   2> 1512357 INFO  (closeThreadPool-11406-thread-8) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1512390 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node 
tag=null
   [junit4]   2> 1512390 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@57dc26e2: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry 
solr.node/com.codahale.metrics.MetricRegistry@71c527e1
   [junit4]   2> 1512392 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm 
tag=null
   [junit4]   2> 1512392 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4aadf777: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry 
solr.jvm/com.codahale.metrics.MetricRegistry@604691d8
   [junit4]   2> 1512392 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty 
tag=null
   [junit4]   2> 1512392 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2f17dee5: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty/com.codahale.metrics.MetricRegistry@336fe81e
   [junit4]   2> 1512395 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@194d594c{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1512395 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@60976e7e{/,null,UNAVAILABLE}
   [junit4]   2> 1512395 INFO  (closeThreadPool-11406-thread-2) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1514338 INFO  (closeThreadPool-11406-thread-7) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@65008f93{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1514338 INFO  (closeThreadPool-11406-thread-7) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5c7d763d{/,null,UNAVAILABLE}
   [junit4]   2> 1514339 INFO  (closeThreadPool-11406-thread-7) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=C40D6521BB10F747 -Dtests.slow=true -Dtests.locale=en-IM 
-Dtests.timezone=Europe/Podgorica -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR    140s J1 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=17284, name=httpShardExecutor-11348-thread-5, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([C40D6521BB10F747:4C595AFB15EC9ABF]:0)
   [junit4]    > Caused by: java.util.concurrent.RejectedExecutionException: 
Task 
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@63996e3b[Not
 completed, task = 
java.util.concurrent.Executors$RunnableAdapter@3a5271f3[Wrapped task = 
org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$528/0x0000000801099440@2fe9a7d8]]
 rejected from 
java.util.concurrent.ScheduledThreadPoolExecutor@63a4d601[Terminated, pool size 
= 0, active threads = 0, queued tasks = 0, completed tasks = 0]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([C40D6521BB10F747]:0)
   [junit4]    >        at 
java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2057)
   [junit4]    >        at 
java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:827)
   [junit4]    >        at 
java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
   [junit4]    >        at 
java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
   [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.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
   [junit4]    >        at 
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:832)
   [junit4]   2> 1514341 INFO  
(SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=en-IM, 
timezone=Europe/Podgorica
   [junit4]   2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14.0.1 
(64-bit)/cpus=6,threads=2,free=136361488,total=530317312
   [junit4]   2> NOTE: All tests run in this JVM: 
[IndexSizeTriggerSizeEstimationTest, TestTolerantSearch, 
BasicDistributedZk2Test, TestSolr4Spatial, 
HdfsTlogReplayBufferedWhileIndexingTest, 
TestUnifiedSolrHighlighterWithoutStoredId, DaemonStreamApiTest, 
TestZkAclsWithHadoopAuth, DocumentAnalysisRequestHandlerTest, 
CoreAdminCreateDiscoverTest, DistributedQueryElevationComponentTest, 
ChaosMonkeySafeLeaderTest, TestDistributedMap, V2StandaloneTest, 
ReplaceNodeTest, RulesTest, SolrTestCaseJ4Test, SpatialRPTFieldTypeTest, 
TestStressRecovery, ResponseLogComponentTest, TestBulkSchemaAPI, 
TestReloadDeadlock, TestHashQParserPlugin, JvmMetricsTest, 
TestMultiValuedNumericRangeQuery, InfixSuggestersTest, 
TestLuceneIndexBackCompat, CloudMLTQParserTest, TestCaffeineCache, 
TestFileDictionaryLookup, SpellingQueryConverterTest, TestRandomFlRTGCloud, 
TestSolrCloudSnapshots, TestCloudDeleteByQuery, FileBasedSpellCheckerTest, 
MultiDestinationAuditLoggerTest, TestOrdValues, HdfsRecoverLeaseTest, 
TestManagedSynonymFilterFactory, CreateRoutedAliasTest, 
TestLegacyNumericRangeQueryBuilder, TriggerSetPropertiesIntegrationTest, 
TestPKIAuthenticationPlugin, RankFieldTest, TestSolrConfigHandlerCloud, 
TestSubQueryTransformerCrossCore, SparseHLLTest, TestCloudSchemaless, 
DirectoryFactoryTest, OverseerStatusTest, TestPHPSerializedResponseWriter, 
ConnectionReuseTest, ClusterStateMockUtilTest, UnloadDistributedZkTest, 
SignificantTermsQParserPluginTest, TestConfigSetsAPIExclusivity, 
TestReplicationHandlerDiskOverFlow, LeaderVoteWaitTimeoutTest, 
ClassificationUpdateProcessorIntegrationTest, TestCoreAdminApis, 
TestSchemaSimilarityResource, TestRandomDVFaceting, TestTermsQParserPlugin, 
TestExportWriter, TestAtomicUpdateErrorCases, HdfsBasicDistributedZk2Test, 
TestFieldCacheSortRandom, CollectionReloadTest, ExplicitHLLTest, 
TestMacroExpander, FullSolrCloudDistribCmdsTest, TestCloudRecovery, 
DataDrivenBlockJoinTest, SplitByPrefixTest, TestLazySolrCluster, TestSolrJ, 
RoutingToNodesWithPropertiesTest, TestWaitForStateWithJettyShutdowns, 
LeaderElectionIntegrationTest, SolrCloudReportersTest, TestIntervalFaceting, 
EchoParamsTest, SuggestComponentContextFilterQueryTest, 
OpenExchangeRatesOrgProviderTest, ResponseHeaderTest, 
TestLegacyBM25SimilarityFactory, TestDistributedSearch]
   [junit4] Completed [525/920 (1!)] on J1 in 140.25s, 1 test, 1 error <<< 
FAILURES!

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

[repro] Revision: 1c0f07ac03f0235adaf5c150f1c6656336e4282f

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

[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 3476 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror 
"-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions 
-XX:+UseShenandoahGC" -Dtests.seed=C40D6521BB10F747 -Dtests.slow=true 
-Dtests.locale=en-IM -Dtests.timezone=Europe/Podgorica -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

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

[...truncated 98 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

[JENKINS-Experimental-GC] Lucene-Solr-8.x-Windows (64bit/jdk-14.0.1) - Build # 1705 - Unstable!

Reply via email to