You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/10/19 23:25:06 UTC
[JENKINS] Lucene-Solr-8.x-Windows (32bit/jdk1.8.0_201) - Build #
1741 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1741/
Java: 32bit/jdk1.8.0_201 -client -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Captured an uncaught exception in thread: Thread[id=12235, name=httpShardExecutor-7996-thread-11, state=RUNNABLE, group=TGRP-TestDistributedSearch]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=12235, name=httpShardExecutor-7996-thread-11, state=RUNNABLE, group=TGRP-TestDistributedSearch]
Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@c2a902 rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5432a[Terminated, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 0]
at __randomizedtesting.SeedInfo.seed([7830F84F8D43D9EC]:0)
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15037 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> 1324987 INFO (SUITE-TestDistributedSearch-seed#[7830F84F8D43D9EC]-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> 1324987 INFO (SUITE-TestDistributedSearch-seed#[7830F84F8D43D9EC]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\data-dir-87-001
[junit4] 2> 1324987 WARN (SUITE-TestDistributedSearch-seed#[7830F84F8D43D9EC]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1324987 INFO (SUITE-TestDistributedSearch-seed#[7830F84F8D43D9EC]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1324997 INFO (SUITE-TestDistributedSearch-seed#[7830F84F8D43D9EC]-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> 1324997 INFO (SUITE-TestDistributedSearch-seed#[7830F84F8D43D9EC]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1324997 INFO (SUITE-TestDistributedSearch-seed#[7830F84F8D43D9EC]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1326043 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\control\cores\collection1
[junit4] 2> 1326050 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1326050 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1326050 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1326050 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1326051 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1326051 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1326051 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1326052 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@59b405{/,null,AVAILABLE}
[junit4] 2> 1326054 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@bf2d85{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:56047}
[junit4] 2> 1326054 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.Server Started @1326006ms
[junit4] 2> 1326054 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=56047, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\control\cores}
[junit4] 2> 1326055 ERROR (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1326055 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1326055 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.7.0
[junit4] 2> 1326055 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1326055 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1326055 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-19T22:24:36.036Z
[junit4] 2> 1326055 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\control\solr.xml
[junit4] 2> 1326071 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1326071 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1326073 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1d81e86, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1327963 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1327990 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1502506[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1327990 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1502506[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1328050 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1f60dd0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1328050 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1f60dd0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1328055 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] 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> 1328115 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1328115 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1328169 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d81e86
[junit4] 2> 1328176 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d81e86
[junit4] 2> 1328176 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d81e86
[junit4] 2> 1328183 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\control\cores
[junit4] 2> 1328183 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1328274 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 1328346 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1328467 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1328483 INFO (coreLoadExecutor-7834-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> 1328483 INFO (coreLoadExecutor-7834-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@1d81e86
[junit4] 2> 1328483 INFO (coreLoadExecutor-7834-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\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\control\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\control\cores\collection1\data\]
[junit4] 2> 1328486 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=25.1279296875, floorSegmentMB=1.5087890625, forceMergeDeletesPctAllowed=12.434878331484896, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20065483062126283, deletesPctAllowed=25.895519553622957
[junit4] 2> 1328651 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1328651 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1328656 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1328656 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1328656 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=25.6923828125, floorSegmentMB=1.4765625, forceMergeDeletesPctAllowed=10.54624287611979, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8521374866031166, deletesPctAllowed=45.52700948043961
[junit4] 2> 1328659 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1328684 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1328747 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1328751 INFO (coreLoadExecutor-7834-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1681020712367685632
[junit4] 2> 1328751 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1328752 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1328752 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1328752 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1328753 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1328753 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1328753 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1328753 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1328754 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1328755 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1330587 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 1330594 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1330594 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1330594 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1330594 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1330594 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1330594 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1330594 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1330594 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b4f976{/,null,AVAILABLE}
[junit4] 2> 1330599 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8d627{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:56171}
[junit4] 2> 1330599 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.s.Server Started @1330551ms
[junit4] 2> 1330599 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=56171, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\shard0\cores}
[junit4] 2> 1330600 ERROR (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1330600 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1330600 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.7.0
[junit4] 2> 1330600 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1330600 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1330600 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-19T22:24:40.581Z
[junit4] 2> 1330600 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\shard0\solr.xml
[junit4] 2> 1330610 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1330610 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1330610 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1d81e86, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1333946 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1333951 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@179a1e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333951 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@179a1e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333967 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@698b27[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333967 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@698b27[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1333970 WARN (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] 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> 1334048 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1334048 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1334102 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d81e86
[junit4] 2> 1334114 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d81e86
[junit4] 2> 1334114 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d81e86
[junit4] 2> 1334118 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\shard0\cores
[junit4] 2> 1334120 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1334204 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 1334258 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1334439 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1334458 INFO (coreLoadExecutor-7852-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> 1334460 INFO (coreLoadExecutor-7852-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@1d81e86
[junit4] 2> 1334460 INFO (coreLoadExecutor-7852-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\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\shard0\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_7830F84F8D43D9EC-001\tempDir-001\shard0\cores\collection1\data\]
[junit4] 2> 1334462 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=25.1279296875, floorSegmentMB=1.5087890625, forceMergeDeletesPctAllowed=12.434878331484896, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20065483062126283, deletesPctAllowed=25.895519553622957
[junit4] 2> 1334693 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1334693 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1334694 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1334694 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1334698 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=25.6923828125, floorSegmentMB=1.4765625, forceMergeDeletesPctAllowed=10.54624287611979, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8521374866031166, deletesPctAllowed=45.52700948043961
[junit4] 2> 1334700 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1334714 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1334768 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1334770 INFO (coreLoadExecutor-7852-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1681020718679064576
[junit4] 2> 1334770 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1334771 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1334771 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1334771 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1334771 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1334771 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1334771 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1334771 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1334774 INFO (TEST-TestDistributedSearch.test-seed#[7830F84F8D43D9EC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1334774 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1334777 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1334781 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1681020718687453184)} 0 3
[junit4] 2> 1334786 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1681020718693744640)} 0 3
[junit4] 2> 1334792 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1681020718697938944)]} 0 4
[junit4] 2> 1334798 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1681020718705278976)]} 0 3
[junit4] 2> 1334802 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1681020718709473280)]} 0 3
[junit4] 2> 1334806 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1681020718715764736)]} 0 2
[junit4] 2> 1334808 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1681020718718910464)]} 0 1
[junit4] 2> 1334812 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1681020718722056192)]} 0 1
[junit4] 2> 1334814 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1681020718725201920)]} 0 1
[junit4] 2> 1334816 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1681020718727299072)]} 0 1
[junit4] 2> 1334820 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1681020718729396224)]} 0 1
[junit4] 2> 1334823 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1681020718733590528)]} 0 1
[junit4] 2> 1334824 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1681020718735687680)]} 0 1
[junit4] 2> 1334827 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1681020718737784832)]} 0 1
[junit4] 2> 1334830 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1681020718740930560)]} 0 1
[junit4] 2> 1334832 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1681020718743027712)]} 0 1
[junit4] 2> 1334834 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1681020718746173440)]} 0 1
[junit4] 2> 1334839 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1681020718750367744)]} 0 1
[junit4] 2> 1334840 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1681020718752464896)]} 0 1
[junit4] 2> 1334842 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1681020718754562048)]} 0 1
[junit4] 2> 1334883 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1334883 INFO (qtp20725501-11922) [ 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 39
[junit4] 2> 1334942 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1334942 INFO (qtp14921952-11942) [ 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 59
[junit4] 2> 1334950 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1681020718864662528)]} 0 4
[junit4] 2> 1334955 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1681020718870953984)]} 0 3
[junit4] 2> 1334961 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1681020718877245440)]} 0 1
[junit4] 2> 1334962 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1681020718880391168)]} 0 1
[junit4] 2> 1334967 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1681020718883536896)]} 0 2
[junit4] 2> 1334969 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1681020718886682624)]} 0 1
[junit4] 2> 1334973 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1681020718889828352)]} 0 1
[junit4] 2> 1334974 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1681020718892974080)]} 0 1
[junit4] 2> 1334976 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1681020718895071232)]} 0 1
[junit4] 2> 1334980 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1681020718898216960)]} 0 1
[junit4] 2> 1334984 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1681020718901362688)]} 0 1
[junit4] 2> 1334987 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1681020718905556992)]} 0 1
[junit4] 2> 1334990 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1681020718908702720)]} 0 1
[junit4] 2> 1334993 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1681020718911848448)]} 0 1
[junit4] 2> 1334996 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1681020718914994176)]} 0 1
[junit4] 2> 1334998 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1681020718917091328)]} 0 1
[junit4] 2> 1335003 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1681020718921285632)]} 0 3
[junit4] 2> 1335007 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1681020718925479936)]} 0 2
[junit4] 2> 1335009 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1681020718928625664)]} 0 1
[junit4] 2> 1335011 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1681020718931771392)]} 0 1
[junit4] 2> 1335014 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1681020718933868544)]} 0 1
[junit4] 2> 1335016 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1681020718935965696)]} 0 1
[junit4] 2> 1335018 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1681020718939111424)]} 0 1
[junit4] 2> 1335020 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1681020718941208576)]} 0 1
[junit4] 2> 1335020 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1681020718941208576)]} 0 1
[junit4] 2> 1335027 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1681020718946451456)]} 0 1
[junit4] 2> 1335029 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1681020718949597184)]} 0 1
[junit4] 2> 1335031 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1681020718951694336)]} 0 0
[junit4] 2> 1335033 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1681020718954840064)]} 0 1
[junit4] 2> 1335044 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1681020718965325824)]} 0 1
[junit4] 2> 1335046 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1681020718968471552)]} 0 1
[junit4] 2> 1335049 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1681020718970568704)]} 0 1
[junit4] 2> 1335051 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1681020718972665856)]} 0 1
[junit4] 2> 1335053 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1681020718974763008)]} 0 1
[junit4] 2> 1335055 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1681020718977908736)]} 0 1
[junit4] 2> 1335058 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1681020718980005888)]} 0 1
[junit4] 2> 1335060 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1681020718982103040)]} 0 1
[junit4] 2> 1335062 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1681020718985248768)]} 0 1
[junit4] 2> 1335064 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1681020718987345920)]} 0 0
[junit4] 2> 1335066 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1681020718989443072)]} 0 1
[junit4] 2> 1335069 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1681020718991540224)]} 0 1
[junit4] 2> 1335070 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1681020718993637376)]} 0 0
[junit4] 2> 1335072 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1681020718995734528)]} 0 0
[junit4] 2> 1335074 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1681020718996783104)]} 0 0
[junit4] 2> 1335076 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1681020718998880256)]} 0 1
[junit4] 2> 1335078 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1681020719000977408)]} 0 0
[junit4] 2> 1335079 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1681020719003074560)]} 0 0
[junit4] 2> 1335081 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1681020719004123136)]} 0 1
[junit4] 2> 1335083 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1681020719006220288)]} 0 0
[junit4] 2> 1335084 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1681020719007268864)]} 0 1
[junit4] 2> 1335086 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1681020719009366016)]} 0 1
[junit4] 2> 1335089 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1681020719012511744)]} 0 1
[junit4] 2> 1335091 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1681020719014608896)]} 0 0
[junit4] 2> 1335092 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1681020719016706048)]} 0 0
[junit4] 2> 1335094 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1681020719017754624)]} 0 0
[junit4] 2> 1335095 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1681020719019851776)]} 0 0
[junit4] 2> 1335098 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1681020719021948928)]} 0 1
[junit4] 2> 1335099 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1681020719024046080)]} 0 0
[junit4] 2> 1335101 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1681020719026143232)]} 0 0
[junit4] 2> 1335103 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1681020719028240384)]} 0 0
[junit4] 2> 1335105 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1681020719029288960)]} 0 0
[junit4] 2> 1335107 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1681020719031386112)]} 0 0
[junit4] 2> 1335108 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1681020719033483264)]} 0 0
[junit4] 2> 1335111 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1681020719035580416)]} 0 1
[junit4] 2> 1335112 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1681020719037677568)]} 0 0
[junit4] 2> 1335114 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1681020719038726144)]} 0 1
[junit4] 2> 1335116 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1681020719040823296)]} 0 0
[junit4] 2> 1335118 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1681020719042920448)]} 0 0
[junit4] 2> 1335119 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1681020719045017600)]} 0 0
[junit4] 2> 1335121 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1681020719046066176)]} 0 0
[junit4] 2> 1335123 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1681020719048163328)]} 0 0
[junit4] 2> 1335125 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1681020719050260480)]} 0 1
[junit4] 2> 1335126 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1681020719052357632)]} 0 0
[junit4] 2> 1335128 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1681020719053406208)]} 0 0
[junit4] 2> 1335130 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1681020719055503360)]} 0 0
[junit4] 2> 1335131 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1681020719057600512)]} 0 0
[junit4] 2> 1335133 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1681020719058649088)]} 0 1
[junit4] 2> 1335135 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1681020719060746240)]} 0 0
[junit4] 2> 1335136 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1681020719061794816)]} 0 0
[junit4] 2> 1335137 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1681020719063891968)]} 0 0
[junit4] 2> 1335139 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1681020719065989120)]} 0 0
[junit4] 2> 1335140 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1681020719067037696)]} 0 0
[junit4] 2> 1335141 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1681020719068086272)]} 0 0
[junit4] 2> 1335142 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1681020719069134848)]} 0 0
[junit4] 2> 1335143 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1681020719070183424)]} 0 0
[junit4] 2> 1335144 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1681020719071232000)]} 0 0
[junit4] 2> 1335146 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1681020719072280576)]} 0 0
[junit4] 2> 1335147 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1681020719074377728)]} 0 0
[junit4] 2> 1335149 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1681020719076474880)]} 0 0
[junit4] 2> 1335151 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1681020719077523456)]} 0 0
[junit4] 2> 1335153 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1681020719079620608)]} 0 0
[junit4] 2> 1335155 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1681020719081717760)]} 0 0
[junit4] 2> 1335157 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1681020719083814912)]} 0 0
[junit4] 2> 1335158 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1681020719085912064)]} 0 0
[junit4] 2> 1335160 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1681020719086960640)]} 0 0
[junit4] 2> 1335161 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1681020719089057792)]} 0 0
[junit4] 2> 1335163 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1681020719090106368)]} 0 0
[junit4] 2> 1335164 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1681020719092203520)]} 0 0
[junit4] 2> 1335166 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1681020719093252096)]} 0 0
[junit4] 2> 1335167 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1681020719094300672)]} 0 0
[junit4] 2> 1335169 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1681020719096397824)]} 0 0
[junit4] 2> 1335170 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1681020719098494976)]} 0 0
[junit4] 2> 1335172 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1681020719099543552)]} 0 0
[junit4] 2> 1335173 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1681020719101640704)]} 0 0
[junit4] 2> 1335174 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1681020719102689280)]} 0 0
[junit4] 2> 1335176 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1681020719104786432)]} 0 0
[junit4] 2> 1335176 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1681020719104786432)]} 0 0
[junit4] 2> 1335178 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1681020719104786433)]} 0 0
[junit4] 2> 1335180 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1681020719107932160)]} 0 0
[junit4] 2> 1335182 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1681020719110029312)]} 0 0
[junit4] 2> 1335183 INFO (qtp20725501-11922) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1681020719112126464)]} 0 0
[junit4] 2> 1335184 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1681020719113175040)]} 0 0
[junit4] 2> 1335185 INFO (qtp20725501-11921) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1681020719114223616)]} 0 0
[junit4] 2> 1335187 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1681020719116320768)]} 0 0
[junit4] 2> 1335188 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1681020719117369344)]} 0 0
[junit4] 2> 1335190 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1681020719119466496)]} 0 0
[junit4] 2> 1335190 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1681020719119466496)]} 0 0
[junit4] 2> 1335190 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1681020719119466497)]} 0 0
[junit4] 2> 1335218 INFO (searcherExecutor-7836-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1335218 INFO (qtp20725501-11922) [ 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 26
[junit4] 2> 1335234 INFO (searcherExecutor-7854-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1335234 INFO (qtp14921952-11942) [ 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 16
[junit4] 2> 1335238 ERROR (qtp20725501-11921) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
[junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:271)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:189)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:337)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2627)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:795)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:568)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1300)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1215)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value"
[junit4] 2> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
[junit4] 2> at java.lang.Integer.parseInt(Integer.java:580)
[junit4] 2> at java.lang.Integer.valueOf(Integer.java:766)
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
[junit4] 2> ... 38 more
[junit4] 2>
[junit4] 2> 1335238 INFO (qtp20725501-11921) [ 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=1
[junit4] 2> 1335243 INFO (qtp20725501-11918) [ x:collection1 ] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1335243 INFO (qtp20725501-11918) [ 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> 1335246 INFO (qtp20725501-11919) [ x:collection1 ] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1335246 INFO (qtp20725501-11919) [ 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> 1335248 ERROR (qtp20725501-11922) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
[junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:274)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:189)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:337)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2627)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:795)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:568)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1300)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1215)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value"
[junit4] 2> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
[junit4] 2> at java.lang.Integer.parseInt(Integer.java:580)
[junit4] 2> at java.lang.Integer.valueOf(Integer.java:766)
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
[junit4] 2> ... 38 more
[junit4] 2>
[junit4] 2> 1335249 INFO (qtp20725501-11922) [ 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=1
[junit4] 2> 1335251 INFO (qtp20725501-11921) [ 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> 1339827 INFO (qtp14921952-11942) [ 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]:4/|[::1]:8/|http://127.0.0.1:56171/collection1|[::1]:6/&rid=127.0.0.1-7516&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146285234&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1339831 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146285234&ids=146,136,5,16,148,128,107,129,8,120&isShard=true&shard.url=[::1]:4/|[::1]:8/|http://127.0.0.1:56171/collection1|[::1]:6/&rid=127.0.0.1-7516&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1339835 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:4/|[::1]:8/|http://127.0.0.1:56171/collection1|[::1]:6/&sort=n_ti1+desc&wt=javabin&version=2} rid=127.0.0.1-7516 hits=68 status=0 QTime=4581
[junit4] 2> 1339835 WARN (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.SlowRequest slow: [collection1] webapp= path=/select params={q=*:*&shards=[::1]:4/|[::1]:8/|http://127.0.0.1:56171/collection1|[::1]:6/&sort=n_ti1+desc&wt=javabin&version=2} rid=127.0.0.1-7516 hits=68 status=0 QTime=4581
[junit4] 2> 1339854 INFO (qtp20725501-11918) [ 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> 1339858 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:56171/collection1&rid=127.0.0.1-7518&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146289837&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1339861 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146289837&ids=111,133,145,13,113,14,3,137,1001,141&isShard=true&shard.url=http://127.0.0.1:56171/collection1&rid=127.0.0.1-7518&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1339863 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56171/collection1&sort=n_ti1+asc&wt=javabin&version=2} rid=127.0.0.1-7518 hits=68 status=0 QTime=7
[junit4] 2> 1339867 INFO (qtp20725501-11919) [ 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> 1341895 INFO (qtp14921952-11942) [ 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=[::1]:8/|[::1]:6/|http://127.0.0.1:56171/collection1&rid=127.0.0.1-7520&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146289850&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341898 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146289850&ids=132,100,102,14,127,138,1001,8,109,131&isShard=true&shard.url=[::1]:8/|[::1]:6/|http://127.0.0.1:56171/collection1&rid=127.0.0.1-7520&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341903 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:8/|[::1]:6/|http://127.0.0.1:56171/collection1&sort=n_f1+desc&wt=javabin&version=2} rid=127.0.0.1-7520 hits=68 status=0 QTime=2034
[junit4] 2> 1341903 WARN (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.SlowRequest slow: [collection1] webapp= path=/select params={q=*:*&shards=[::1]:8/|[::1]:6/|http://127.0.0.1:56171/collection1&sort=n_f1+desc&wt=javabin&version=2} rid=127.0.0.1-7520 hits=68 status=0 QTime=2034
[junit4] 2> 1341912 INFO (qtp20725501-11922) [ 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> 1341919 INFO (qtp14921952-11942) [ 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=[::1]:6/|[::1]:4/|http://127.0.0.1:56171/collection1&rid=127.0.0.1-7522&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291897&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1341921 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291897&ids=1,2,103,16,104,17,6,116,119,141&isShard=true&shard.url=[::1]:6/|[::1]:4/|http://127.0.0.1:56171/collection1&rid=127.0.0.1-7522&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341923 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:6/|[::1]:4/|http://127.0.0.1:56171/collection1&sort=n_f1+asc&wt=javabin&version=2} rid=127.0.0.1-7522 hits=68 status=0 QTime=7
[junit4] 2> 1341925 INFO (qtp20725501-11921) [ 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> 1341928 INFO (qtp14921952-11942) [ 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=[::1]:6/|http://127.0.0.1:56171/collection1|[::1]:8/&rid=127.0.0.1-7524&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291908&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341930 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291908&ids=132,12,122,2,146,3,103,127,106,109&isShard=true&shard.url=[::1]:6/|http://127.0.0.1:56171/collection1|[::1]:8/&rid=127.0.0.1-7524&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341931 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:6/|http://127.0.0.1:56171/collection1|[::1]:8/&sort=n_tf1+desc&wt=javabin&version=2} rid=127.0.0.1-7524 hits=68 status=0 QTime=4
[junit4] 2> 1341933 INFO (qtp20725501-11918) [ 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> 1341936 INFO (qtp14921952-11942) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:56171/collection1&rid=127.0.0.1-7526&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291915&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341938 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291915&ids=110,123,145,14,135,114,6,118,9,131&isShard=true&shard.url=http://127.0.0.1:56171/collection1&rid=127.0.0.1-7526&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341938 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56171/collection1&sort=n_tf1+asc&wt=javabin&version=2} rid=127.0.0.1-7526 hits=68 status=0 QTime=5
[junit4] 2> 1341938 INFO (qtp20725501-11919) [ 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> 1341945 INFO (qtp14921952-11942) [ 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]:6/|http://127.0.0.1:56171/collection1|[::1]:8/|[::1]:4/&rid=127.0.0.1-7528&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291924&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341947 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291924&ids=112,13,102,125,115,6,7,118,108,140&isShard=true&shard.url=[::1]:6/|http://127.0.0.1:56171/collection1|[::1]:8/|[::1]:4/&rid=127.0.0.1-7528&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341948 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:6/|http://127.0.0.1:56171/collection1|[::1]:8/|[::1]:4/&sort=n_d1+desc&wt=javabin&version=2} rid=127.0.0.1-7528 hits=68 status=0 QTime=5
[junit4] 2> 1341950 INFO (qtp20725501-11922) [ 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> 1341954 INFO (qtp14921952-11942) [ 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:56171/collection1|[::1]:6/|[::1]:8/&rid=127.0.0.1-7530&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291933&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341955 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291933&ids=113,103,5,116,117,8,119,109,130,120&isShard=true&shard.url=http://127.0.0.1:56171/collection1|[::1]:6/|[::1]:8/&rid=127.0.0.1-7530&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341957 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56171/collection1|[::1]:6/|[::1]:8/&sort=n_d1+asc&wt=javabin&version=2} rid=127.0.0.1-7530 hits=68 status=0 QTime=4
[junit4] 2> 1341959 INFO (qtp20725501-11921) [ 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> 1341962 INFO (qtp14921952-11942) [ 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:56171/collection1|[::1]:4/|[::1]:8/&rid=127.0.0.1-7532&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291942&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341963 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291942&ids=132,12,123,136,15,149,139,108,119,142&isShard=true&shard.url=http://127.0.0.1:56171/collection1|[::1]:4/|[::1]:8/&rid=127.0.0.1-7532&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341964 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56171/collection1|[::1]:4/|[::1]:8/&sort=n_td1+desc&wt=javabin&version=2} rid=127.0.0.1-7532 hits=68 status=0 QTime=3
[junit4] 2> 1341966 INFO (qtp20725501-11918) [ 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> 1341969 INFO (qtp14921952-11942) [ 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:56171/collection1|[::1]:8/|[::1]:4/&rid=127.0.0.1-7534&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291949&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341970 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291949&ids=143,2,135,114,115,128,117,107,130,120&isShard=true&shard.url=http://127.0.0.1:56171/collection1|[::1]:8/|[::1]:4/&rid=127.0.0.1-7534&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341970 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56171/collection1|[::1]:8/|[::1]:4/&sort=n_td1+asc&wt=javabin&version=2} rid=127.0.0.1-7534 hits=68 status=0 QTime=3
[junit4] 2> 1341970 INFO (qtp20725501-11919) [ 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> 1341976 INFO (qtp14921952-11942) [ 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=[::1]:8/|[::1]:6/|http://127.0.0.1:56171/collection1|[::1]:4/&rid=127.0.0.1-7536&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291955&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341977 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291955&ids=121,1,133,100,101,103,6,7,9,109&isShard=true&shard.url=[::1]:8/|[::1]:6/|http://127.0.0.1:56171/collection1|[::1]:4/&rid=127.0.0.1-7536&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341979 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:8/|[::1]:6/|http://127.0.0.1:56171/collection1|[::1]:4/&sort=n_l1+desc&wt=javabin&version=2} rid=127.0.0.1-7536 hits=68 status=0 QTime=3
[junit4] 2> 1341980 INFO (qtp20725501-11922) [ 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> 1341983 INFO (qtp14921952-11942) [ 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:56171/collection1|[::1]:4/|[::1]:6/|[::1]:8/&rid=127.0.0.1-7538&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291963&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341985 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291963&ids=110,143,134,102,124,147,115,126,1001,117&isShard=true&shard.url=http://127.0.0.1:56171/collection1|[::1]:4/|[::1]:6/|[::1]:8/&rid=127.0.0.1-7538&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341986 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56171/collection1|[::1]:4/|[::1]:6/|[::1]:8/&sort=n_l1+asc&wt=javabin&version=2} rid=127.0.0.1-7538 hits=68 status=0 QTime=4
[junit4] 2> 1341988 INFO (qtp20725501-11921) [ 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> 1341991 INFO (qtp14921952-11942) [ 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:56171/collection1&rid=127.0.0.1-7540&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291971&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1341993 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291971&ids=110,13,113,103,127,107,9,108,130,131&isShard=true&shard.url=http://127.0.0.1:56171/collection1&rid=127.0.0.1-7540&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341995 INFO (qtp14921952-11943) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:56171/collection1&sort=n_tl1+desc&wt=javabin&version=2} rid=127.0.0.1-7540 hits=68 status=0 QTime=4
[junit4] 2> 1341996 INFO (qtp20725501-11918) [ 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> 1342000 INFO (qtp14921952-11942) [ 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/|[::1]:6/|http://127.0.0.1:56171/collection1&rid=127.0.0.1-7542&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603146291979&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1342002 INFO (qtp14921952-11938) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603146291979&ids=143,144,122,100,123,4,104,117,139,120&isShard=true&shard.url=[::1]:4/|[::1]:6/|http://127.0.0.1:56171/collection1&rid=127.0.0.1-7542&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1342003 INFO (qtp14921952-11939) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:
[...truncated too long message...]
ker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> Okt 19, 2020 6:27:04 PM com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNUNG: Uncaught exception in thread: Thread[httpShardExecutor-7996-thread-10,5,TGRP-TestDistributedSearch]
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@f4b691 rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5432a[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
[junit4] 2> at __randomizedtesting.SeedInfo.seed([7830F84F8D43D9EC]:0)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
[junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
[junit4] 2> at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
[junit4] 2> at org.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.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1476308 INFO (closeThreadPool-8036-thread-8) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@16b154a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1476308 INFO (closeThreadPool-8036-thread-8) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1d6ff5a{/,null,UNAVAILABLE}
[junit4] 2> 1476309 INFO (closeThreadPool-8036-thread-8) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=7830F84F8D43D9EC -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=America/Manaus -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 151s J0 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=12235, name=httpShardExecutor-7996-thread-11, state=RUNNABLE, group=TGRP-TestDistributedSearch]
[junit4] > Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@c2a902 rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5432a[Terminated, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 0]
[junit4] > at __randomizedtesting.SeedInfo.seed([7830F84F8D43D9EC]:0)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
[junit4] > at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
[junit4] > at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)Throwable #2: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=12234, name=httpShardExecutor-7996-thread-10, state=RUNNABLE, group=TGRP-TestDistributedSearch]
[junit4] > Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@f4b691 rejected from java.util.concurrent.ScheduledThreadPoolExecutor@5432a[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
[junit4] > at __randomizedtesting.SeedInfo.seed([7830F84F8D43D9EC]:0)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2063)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:830)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:326)
[junit4] > at java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:533)
[junit4] > at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
[junit4] > at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1476313 INFO (SUITE-TestDistributedSearch-seed#[7830F84F8D43D9EC]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87), sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=de, timezone=America/Manaus
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_201 (32-bit)/cpus=6,threads=1,free=137681408,total=326631424
[junit4] 2> NOTE: All tests run in this JVM: [MultiSolrCloudTestCaseTest, SpellPossibilityIteratorTest, TestFieldCollectionResource, TestDistribDocBasedVersion, ComputePlanActionTest, ZkNodePropsTest, AnalysisAfterCoreReloadTest, IndexSizeTriggerSizeEstimationTest, TestDistributedStatsComponentCardinality, LukeRequestHandlerTest, CurrencyRangeFacetCloudTest, CoreAdminCreateDiscoverTest, HdfsBasicDistributedZk2Test, TestJoin, TestSolrTestCaseJ4, TestCoreDiscovery, HdfsRecoverLeaseTest, OverseerSolrResponseUnsafeSerializationTest, CollectionStateFormat2Test, TestCloudConsistency, TestSegmentSorting, TestWriterPerf, LeaderTragicEventTest, TestCloudNestedDocsSort, ConcurrentDeleteAndCreateCollectionTest, DirectoryFactoryTest, TestFastLRUCache, ZkCollectionPropsCachingTest, DistribJoinFromCollectionTest, NoCacheHeaderTest, TokenizerChainTest, TestReplicationHandlerDiskOverFlow, TestBlendedInfixSuggestions, TestCoreAdminApis, BasicAuthOnSingleNodeTest, RulesTest, DateMathParserTest, ExternalFileFieldSortTest, CloudReplicaSourceTest, TestHashPartitioner, TestFieldCacheSort, TestMultiValuedNumericRangeQuery, ClassificationUpdateProcessorFactoryTest, TestJettySolrRunner, TestComponentsName, DaemonStreamApiTest, TestClassicSimilarityFactory, ShardRoutingTest, SecurityConfHandlerTest, RequestLoggingTest, SplitByPrefixTest, TestSimExecutePlanAction, TestInPlaceUpdatesDistrib, SuggesterTSTTest, TestUseDocValuesAsStored, TestRandomFlRTGCloud, TestConfigOverlay, TestXmlQParserPlugin, TestLazyCores, TestCircuitBreaker, RootFieldTest, TestCloudPseudoReturnFields, NumericFieldsTest, TestSolrCloudWithKerberosAlt, BooleanFieldTest, DistributedIntervalFacetingTest, RegexBoostProcessorTest, CreateCollectionCleanupTest, TestDistributedMissingSort, TestRTimerTree, TestSimNodeLostTrigger, TestExportWriter, TestGraphTermsQParserPlugin, SolrInfoBeanTest, TestSubQueryTransformer, TestExactStatsCache, TestTlogReplayVsRecovery, TimeRoutedAliasUpdateProcessorTest, V2StandaloneTest, HdfsLockFactoryTest, TestContainerPlugin, TestWaitForStateWithJettyShutdowns, HLLUtilTest, TestPackages, HdfsTlogReplayBufferedWhileIndexingTest, CloudExitableDirectoryReaderTest, TestLMJelinekMercerSimilarityFactory, TestTestInjection, SolrIndexSplitterTest, JvmMetricsTest, TestDistributedSearch]
[junit4] Completed [479/921 (1!)] on J0 in 152.35s, 1 test, 1 error <<< FAILURES!
[...truncated 49294 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1741/consoleText
[repro] Revision: 15d2bdd28b890b7bc478ca3ae8724eb68056d8d4
[repro] Ant options: "-Dargs=-client -XX:+UseParallelGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestDistributedSearch
[repro] ant compile-test
[...truncated 3472 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror "-Dargs=-client -XX:+UseParallelGC" -Dtests.seed=7830F84F8D43D9EC -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=America/Manaus -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 88 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.TestDistributedSearch
[repro] Exiting with code 0
[...truncated 98 lines...]
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-13.0.2) - Build # 1742
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1742/
Java: 64bit/jdk-13.0.2 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Captured an uncaught exception in thread: Thread[id=1176, name=httpShardExecutor-595-thread-9, state=RUNNABLE, group=TGRP-TestDistributedSearch]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1176, name=httpShardExecutor-595-thread-9, state=RUNNABLE, group=TGRP-TestDistributedSearch]
at __randomizedtesting.SeedInfo.seed([596B122F68597:8851A96B8C0AE86F]:0)
Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@47714343[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@4d892833[Wrapped task = org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$508/0x0000000801041440@434ecff2]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@273c5c9[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
at __randomizedtesting.SeedInfo.seed([596B122F68597]:0)
at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055)
at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825)
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:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:830)
Build Log:
[...truncated 13668 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> 128486 INFO (SUITE-TestDistributedSearch-seed#[596B122F68597]-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> 128487 INFO (SUITE-TestDistributedSearch-seed#[596B122F68597]-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_596B122F68597-001\data-dir-4-001
[junit4] 2> 128487 WARN (SUITE-TestDistributedSearch-seed#[596B122F68597]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=13 numCloses=13
[junit4] 2> 128488 INFO (SUITE-TestDistributedSearch-seed#[596B122F68597]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 128490 INFO (SUITE-TestDistributedSearch-seed#[596B122F68597]-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> 128490 INFO (SUITE-TestDistributedSearch-seed#[596B122F68597]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 128490 INFO (SUITE-TestDistributedSearch-seed#[596B122F68597]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /co/
[junit4] 2> 129188 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] 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_596B122F68597-001\tempDir-001\control\cores\collection1
[junit4] 2> 129193 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 129194 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 129194 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 129194 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
[junit4] 2> 129195 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 129195 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 129195 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 129195 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1c3b81fd{/co,null,AVAILABLE}
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@390071a4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:58861}
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.Server Started @129792ms
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/co, hostPort=58861, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_596B122F68597-001\tempDir-001\control\cores}
[junit4] 2> 129198 ERROR (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-20T06:03:24.752533400Z
[junit4] 2> 129198 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] 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_596B122F68597-001\tempDir-001\control\solr.xml
[junit4] 2> 129205 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 129205 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 129205 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1458fda1, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 129279 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 129282 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4512752a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 129282 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4512752a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 129287 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@455319b4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 129287 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@455319b4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 129289 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] 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> 129320 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 129320 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 129340 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1458fda1
[junit4] 2> 129347 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1458fda1
[junit4] 2> 129347 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1458fda1
[junit4] 2> 129350 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] 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_596B122F68597-001\tempDir-001\control\cores
[junit4] 2> 129350 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 129369 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 129424 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 129431 WARN (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.D.s.TrieFloatField Solr loaded a deprecated plugin/analysis class [solr.TrieFloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 129433 WARN (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.D.s.TrieDoubleField Solr loaded a deprecated plugin/analysis class [solr.TrieDoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 129445 WARN (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.D.s.TrieDateField Solr loaded a deprecated plugin/analysis class [solr.TrieDateField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 129473 WARN (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.D.s.WordDelimiterFilterFactory Solr loaded a deprecated plugin/analysis class [solr.WordDelimiterFilterFactory]. Please consult documentation how to replace it accordingly.
[junit4] 2> 129537 WARN (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.D.s.EnumField Solr loaded a deprecated plugin/analysis class [solr.EnumField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 129677 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 129705 INFO (coreLoadExecutor-433-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> 129705 INFO (coreLoadExecutor-433-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@1458fda1
[junit4] 2> 129705 INFO (coreLoadExecutor-433-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_596B122F68597-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_596B122F68597-001\tempDir-001\control\cores\collection1\data\]
[junit4] 2> 129705 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1564512637634315]
[junit4] 2> 129848 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 129848 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 129851 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 129851 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 129851 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=62.1279296875, floorSegmentMB=0.5224609375, forceMergeDeletesPctAllowed=20.138189644047323, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.34333325471539
[junit4] 2> 129854 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 129890 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 129977 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 129977 INFO (coreLoadExecutor-433-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1681049576308473856
[junit4] 2> 129977 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 129982 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 129982 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 129982 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 129984 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 129985 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 129985 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 129986 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 129987 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 129987 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 130945 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] 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_596B122F68597-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 130953 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 130954 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 130954 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 130954 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
[junit4] 2> 130955 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 130955 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 130955 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 130955 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60d94e5d{/co,null,AVAILABLE}
[junit4] 2> 130971 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6bd4dc9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:58906}
[junit4] 2> 130971 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.s.Server Started @131566ms
[junit4] 2> 130971 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/co, hostPort=58906, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_596B122F68597-001\tempDir-001\shard0\cores}
[junit4] 2> 130972 ERROR (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 130972 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 130972 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 130972 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 130972 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 130973 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-20T06:03:26.527618100Z
[junit4] 2> 130973 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] 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_596B122F68597-001\tempDir-001\shard0\solr.xml
[junit4] 2> 130981 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 130981 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 130982 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1458fda1, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 131113 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 131116 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@c083b4c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 131116 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@c083b4c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 131123 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@390d1c6a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 131123 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@390d1c6a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 131124 WARN (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] 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> 131156 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 131156 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 131189 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1458fda1
[junit4] 2> 131210 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1458fda1
[junit4] 2> 131210 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1458fda1
[junit4] 2> 131217 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] 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_596B122F68597-001\tempDir-001\shard0\cores
[junit4] 2> 131217 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 131249 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 131305 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 131436 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 131473 INFO (coreLoadExecutor-451-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> 131478 INFO (coreLoadExecutor-451-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@1458fda1
[junit4] 2> 131478 INFO (coreLoadExecutor-451-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_596B122F68597-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_596B122F68597-001\tempDir-001\shard0\cores\collection1\data\]
[junit4] 2> 131478 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1564512637634315]
[junit4] 2> 131677 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 131677 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 131683 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 131683 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 131685 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=62.1279296875, floorSegmentMB=0.5224609375, forceMergeDeletesPctAllowed=20.138189644047323, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=31.34333325471539
[junit4] 2> 131693 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 131783 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 131897 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 131897 INFO (coreLoadExecutor-451-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1681049578321739776
[junit4] 2> 131897 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 131897 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 131897 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 131897 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 131901 INFO (TEST-TestDistributedSearch.test-seed#[596B122F68597]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 131901 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 131905 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 131906 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 131906 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 131908 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 131912 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 131936 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1681049578337468416)} 0 25
[junit4] 2> 131945 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1681049578367877120)} 0 4
[junit4] 2> 132001 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[1 (1681049578376265728)]} 0 55
[junit4] 2> 132021 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[1 (1681049578434985984)]} 0 17
[junit4] 2> 132037 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[2 (1681049578460151808)]} 0 9
[junit4] 2> 132049 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[2 (1681049578472734720)]} 0 8
[junit4] 2> 132056 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[3 (1681049578488463360)]} 0 2
[junit4] 2> 132056 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[3 (1681049578488463360)]} 0 1
[junit4] 2> 132061 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[4 (1681049578493706240)]} 0 1
[junit4] 2> 132063 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[4 (1681049578495803392)]} 0 1
[junit4] 2> 132065 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[5 (1681049578497900544)]} 0 1
[junit4] 2> 132069 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[5 (1681049578502094848)]} 0 1
[junit4] 2> 132077 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[6 (1681049578506289152)]} 0 7
[junit4] 2> 132081 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[6 (1681049578510483456)]} 0 1
[junit4] 2> 132093 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[7 (1681049578525163520)]} 0 3
[junit4] 2> 132101 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[7 (1681049578528309248)]} 0 8
[junit4] 2> 132108 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[8 (1681049578539843584)]} 0 3
[junit4] 2> 132109 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[8 (1681049578544037888)]} 0 1
[junit4] 2> 132112 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[9 (1681049578544037888)]} 0 1
[junit4] 2> 132114 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[9 (1681049578547183616)]} 0 1
[junit4] 2> 132235 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 132235 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 115
[junit4] 2> 132354 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 132357 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 120
[junit4] 2> 132393 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[10 (1681049578808279040)]} 0 34
[junit4] 2> 132413 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[10 (1681049578846027776)]} 0 16
[junit4] 2> 132423 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[11 (1681049578869096448)]} 0 4
[junit4] 2> 132461 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[11 (1681049578875387904)]} 0 36
[junit4] 2> 132476 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[12 (1681049578925719552)]} 0 2
[junit4] 2> 132480 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[12 (1681049578929913856)]} 0 2
[junit4] 2> 132486 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[13 (1681049578935156736)]} 0 2
[junit4] 2> 132492 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[13 (1681049578943545344)]} 0 2
[junit4] 2> 132498 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[1001 (1681049578947739648)]} 0 4
[junit4] 2> 132502 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[1001 (1681049578954031104)]} 0 2
[junit4] 2> 132509 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[14 (1681049578961371136)]} 0 3
[junit4] 2> 132514 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[14 (1681049578967662592)]} 0 1
[junit4] 2> 132517 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[15 (1681049578968711168)]} 0 1
[junit4] 2> 132517 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[15 (1681049578971856896)]} 0 1
[junit4] 2> 132521 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[16 (1681049578976051200)]} 0 1
[junit4] 2> 132521 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[16 (1681049578976051200)]} 0 1
[junit4] 2> 132527 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[17 (1681049578982342656)]} 0 1
[junit4] 2> 132531 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[17 (1681049578984439808)]} 0 1
[junit4] 2> 132534 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[100 (1681049578988634112)]} 0 1
[junit4] 2> 132537 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[100 (1681049578992828416)]} 0 1
[junit4] 2> 132541 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[101 (1681049578994925568)]} 0 2
[junit4] 2> 132543 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[101 (1681049578999119872)]} 0 1
[junit4] 2> 132547 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[102 (1681049579001217024)]} 0 1
[junit4] 2> 132550 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[102 (1681049579005411328)]} 0 1
[junit4] 2> 132550 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[103 (1681049579006459904)]} 0 1
[junit4] 2> 132553 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[103 (1681049579009605632)]} 0 1
[junit4] 2> 132557 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[104 (1681049579012751360)]} 0 1
[junit4] 2> 132557 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[104 (1681049579013799936)]} 0 1
[junit4] 2> 132561 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[105 (1681049579017994240)]} 0 1
[junit4] 2> 132565 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[105 (1681049579022188544)]} 0 1
[junit4] 2> 132570 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[106 (1681049579027431424)]} 0 1
[junit4] 2> 132577 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[106 (1681049579030577152)]} 0 2
[junit4] 2> 132585 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[107 (1681049579042111488)]} 0 2
[junit4] 2> 132585 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[107 (1681049579043160064)]} 0 1
[junit4] 2> 132589 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[108 (1681049579047354368)]} 0 1
[junit4] 2> 132591 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[108 (1681049579049451520)]} 0 1
[junit4] 2> 132601 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[109 (1681049579056791552)]} 0 1
[junit4] 2> 132605 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[109 (1681049579064131584)]} 0 1
[junit4] 2> 132612 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[110 (1681049579070423040)]} 0 1
[junit4] 2> 132614 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[110 (1681049579072520192)]} 0 1
[junit4] 2> 132615 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[111 (1681049579074617344)]} 0 0
[junit4] 2> 132626 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[111 (1681049579078811648)]} 0 7
[junit4] 2> 132633 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[112 (1681049579086151680)]} 0 1
[junit4] 2> 132634 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[112 (1681049579094540288)]} 0 1
[junit4] 2> 132637 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[113 (1681049579094540288)]} 0 1
[junit4] 2> 132642 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[113 (1681049579100831744)]} 0 2
[junit4] 2> 132645 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[114 (1681049579103977472)]} 0 1
[junit4] 2> 132647 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[114 (1681049579107123200)]} 0 1
[junit4] 2> 132649 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[115 (1681049579109220352)]} 0 1
[junit4] 2> 132653 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[115 (1681049579112366080)]} 0 2
[junit4] 2> 132657 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[116 (1681049579116560384)]} 0 1
[junit4] 2> 132665 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[116 (1681049579124948992)]} 0 3
[junit4] 2> 132677 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[117 (1681049579135434752)]} 0 5
[junit4] 2> 132682 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[117 (1681049579144871936)]} 0 1
[junit4] 2> 132685 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[118 (1681049579148017664)]} 0 1
[junit4] 2> 132689 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[118 (1681049579152211968)]} 0 1
[junit4] 2> 132697 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[119 (1681049579160600576)]} 0 1
[junit4] 2> 132703 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[119 (1681049579160600576)]} 0 3
[junit4] 2> 132708 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[120 (1681049579168989184)]} 0 2
[junit4] 2> 132710 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[120 (1681049579173183488)]} 0 1
[junit4] 2> 132714 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[121 (1681049579177377792)]} 0 1
[junit4] 2> 132718 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[121 (1681049579180523520)]} 0 1
[junit4] 2> 132721 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[122 (1681049579183669248)]} 0 1
[junit4] 2> 132724 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[122 (1681049579187863552)]} 0 1
[junit4] 2> 132732 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[123 (1681049579194155008)]} 0 1
[junit4] 2> 132734 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[123 (1681049579198349312)]} 0 1
[junit4] 2> 132737 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[124 (1681049579200446464)]} 0 1
[junit4] 2> 132741 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[124 (1681049579204640768)]} 0 1
[junit4] 2> 132741 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[125 (1681049579206737920)]} 0 1
[junit4] 2> 132745 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[125 (1681049579210932224)]} 0 2
[junit4] 2> 132745 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[126 (1681049579210932224)]} 0 1
[junit4] 2> 132749 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[126 (1681049579215126528)]} 0 1
[junit4] 2> 132753 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[127 (1681049579219320832)]} 0 1
[junit4] 2> 132756 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[127 (1681049579219320832)]} 0 1
[junit4] 2> 132759 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[128 (1681049579223515136)]} 0 1
[junit4] 2> 132760 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[128 (1681049579226660864)]} 0 1
[junit4] 2> 132763 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[129 (1681049579228758016)]} 0 1
[junit4] 2> 132766 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[129 (1681049579231903744)]} 0 1
[junit4] 2> 132767 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[130 (1681049579234000896)]} 0 1
[junit4] 2> 132771 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[130 (1681049579238195200)]} 0 1
[junit4] 2> 132775 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[131 (1681049579241340928)]} 0 1
[junit4] 2> 132777 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[131 (1681049579243438080)]} 0 0
[junit4] 2> 132780 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[132 (1681049579245535232)]} 0 1
[junit4] 2> 132785 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[132 (1681049579250778112)]} 0 2
[junit4] 2> 132786 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[133 (1681049579253923840)]} 0 1
[junit4] 2> 132789 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[133 (1681049579256020992)]} 0 1
[junit4] 2> 132791 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[134 (1681049579258118144)]} 0 1
[junit4] 2> 132795 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[134 (1681049579262312448)]} 0 1
[junit4] 2> 132798 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[135 (1681049579265458176)]} 0 2
[junit4] 2> 132801 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[135 (1681049579267555328)]} 0 1
[junit4] 2> 132801 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[136 (1681049579269652480)]} 0 1
[junit4] 2> 132801 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[136 (1681049579269652480)]} 0 1
[junit4] 2> 132809 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[137 (1681049579278041088)]} 0 2
[junit4] 2> 132816 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[137 (1681049579282235392)]} 0 2
[junit4] 2> 132817 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[138 (1681049579286429696)]} 0 1
[junit4] 2> 132821 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[138 (1681049579290624000)]} 0 1
[junit4] 2> 132821 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[139 (1681049579290624000)]} 0 1
[junit4] 2> 132827 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[139 (1681049579294818304)]} 0 1
[junit4] 2> 132829 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[140 (1681049579296915456)]} 0 1
[junit4] 2> 132833 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[140 (1681049579300061184)]} 0 2
[junit4] 2> 132835 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[141 (1681049579303206912)]} 0 1
[junit4] 2> 132837 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[141 (1681049579307401216)]} 0 1
[junit4] 2> 132841 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[142 (1681049579311595520)]} 0 1
[junit4] 2> 132846 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[142 (1681049579315789824)]} 0 1
[junit4] 2> 132849 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[143 (1681049579318935552)]} 0 1
[junit4] 2> 132849 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[143 (1681049579319984128)]} 0 1
[junit4] 2> 132853 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[144 (1681049579324178432)]} 0 1
[junit4] 2> 132856 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[144 (1681049579326275584)]} 0 1
[junit4] 2> 132858 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[145 (1681049579329421312)]} 0 1
[junit4] 2> 132861 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[145 (1681049579331518464)]} 0 1
[junit4] 2> 132864 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[146 (1681049579334664192)]} 0 1
[junit4] 2> 132867 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[146 (1681049579336761344)]} 0 1
[junit4] 2> 132869 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[147 (1681049579339907072)]} 0 1
[junit4] 2> 132872 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[147 (1681049579342004224)]} 0 1
[junit4] 2> 132873 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[148 (1681049579345149952)]} 0 1
[junit4] 2> 132878 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[148 (1681049579349344256)]} 0 1
[junit4] 2> 132885 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[149 (1681049579352489984)]} 0 1
[junit4] 2> 132890 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={wt=javabin&version=2}{add=[149 (1681049579360878592)]} 0 3
[junit4] 2> 133007 INFO (searcherExecutor-435-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 133007 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 108
[junit4] 2> 133149 INFO (searcherExecutor-453-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 133150 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/co path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 139
[junit4] 2> 133155 ERROR (qtp1517042176-854) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
[junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:271)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:189)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:337)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2627)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:795)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:568)
[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:830)
[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:658)
[junit4] 2> at java.base/java.lang.Integer.valueOf(Integer.java:989)
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
[junit4] 2> ... 38 more
[junit4] 2>
[junit4] 2> 133155 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*&distrib=false&start=non_numeric_value&wt=javabin&version=2} status=400 QTime=3
[junit4] 2> 133161 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 133161 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 133167 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 133167 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=5
[junit4] 2> 133169 ERROR (qtp1517042176-856) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
[junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:274)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:189)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:337)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2627)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:795)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:568)
[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:830)
[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:658)
[junit4] 2> at java.base/java.lang.Integer.valueOf(Integer.java:989)
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
[junit4] 2> ... 38 more
[junit4] 2>
[junit4] 2> 133169 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*&distrib=false&rows=non_numeric_value&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 133185 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=16
[junit4] 2> 133222 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:58906/co/collection1|[::1]:8/co|[::1]:4/co&rid=127.0.0.1-4600&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173808758&isShard=true&wt=javabin} hits=68 status=0 QTime=2
[junit4] 2> 133233 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173808758&ids=110,121,101,14,137,104,139,128,9,10&isShard=true&shard.url=http://127.0.0.1:58906/co/collection1|[::1]:8/co|[::1]:4/co&rid=127.0.0.1-4600&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 133258 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=http://127.0.0.1:58906/co/collection1|[::1]:8/co|[::1]:4/co&sort=n_ti1+desc&wt=javabin&version=2} rid=127.0.0.1-4600 hits=68 status=0 QTime=54
[junit4] 2> 133265 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 133281 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:58906/co/collection1&rid=127.0.0.1-4602&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173808825&isShard=true&wt=javabin} hits=68 status=0 QTime=4
[junit4] 2> 133286 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173808825&ids=100,123,113,135,126,5,105,106,129,108&isShard=true&shard.url=http://127.0.0.1:58906/co/collection1&rid=127.0.0.1-4602&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 133306 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=http://127.0.0.1:58906/co/collection1&sort=n_ti1+asc&wt=javabin&version=2} rid=127.0.0.1-4602 hits=68 status=0 QTime=34
[junit4] 2> 133313 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 133325 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co 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:58906/co/collection1|[::1]:6/co|[::1]:4/co|[::1]:8/co&rid=127.0.0.1-4604&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173808871&isShard=true&wt=javabin} hits=68 status=0 QTime=5
[junit4] 2> 133329 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173808871&ids=132,113,4,125,148,126,149,6,117,7&isShard=true&shard.url=http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:4/co|[::1]:8/co&rid=127.0.0.1-4604&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 133341 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:4/co|[::1]:8/co&sort=n_f1+desc&wt=javabin&version=2} rid=127.0.0.1-4604 hits=68 status=0 QTime=23
[junit4] 2> 133347 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 135773 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[::1]:6/co|http://127.0.0.1:58906/co/collection1|[::1]:8/co&rid=127.0.0.1-4606&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173808907&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 135776 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173808907&ids=110,13,124,14,103,127,139,1001,118,9&isShard=true&shard.url=[::1]:6/co|http://127.0.0.1:58906/co/collection1|[::1]:8/co&rid=127.0.0.1-4606&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 135780 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=[::1]:6/co|http://127.0.0.1:58906/co/collection1|[::1]:8/co&sort=n_f1+asc&wt=javabin&version=2} rid=127.0.0.1-4606 hits=68 status=0 QTime=2427
[junit4] 2> 135780 WARN (qtp452066416-872) [ x:collection1 ] o.a.s.c.S.SlowRequest slow: [collection1] webapp=/co path=/select params={q=*:*&shards=[::1]:6/co|http://127.0.0.1:58906/co/collection1|[::1]:8/co&sort=n_f1+asc&wt=javabin&version=2} rid=127.0.0.1-4606 hits=68 status=0 QTime=2427
[junit4] 2> 135786 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 138013 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[::1]:8/co|http://127.0.0.1:58906/co/collection1|[::1]:4/co&rid=127.0.0.1-4608&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173811344&isShard=true&wt=javabin} hits=68 status=0 QTime=3
[junit4] 2> 138021 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173811344&ids=133,144,1,14,136,126,105,139,106,118&isShard=true&shard.url=[::1]:8/co|http://127.0.0.1:58906/co/collection1|[::1]:4/co&rid=127.0.0.1-4608&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 138021 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=[::1]:8/co|http://127.0.0.1:58906/co/collection1|[::1]:4/co&sort=n_tf1+desc&wt=javabin&version=2} rid=127.0.0.1-4608 hits=68 status=0 QTime=2233
[junit4] 2> 138021 WARN (qtp452066416-873) [ x:collection1 ] o.a.s.c.S.SlowRequest slow: [collection1] webapp=/co path=/select params={q=*:*&shards=[::1]:8/co|http://127.0.0.1:58906/co/collection1|[::1]:4/co&sort=n_tf1+desc&wt=javabin&version=2} rid=127.0.0.1-4608 hits=68 status=0 QTime=2233
[junit4] 2> 138029 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 138078 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:4/co|[::1]:8/co&rid=127.0.0.1-4610&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173813624&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 138081 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173813624&ids=143,122,145,123,13,147,4,127,17,128&isShard=true&shard.url=http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:4/co|[::1]:8/co&rid=127.0.0.1-4610&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 138089 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:4/co|[::1]:8/co&sort=n_tf1+asc&wt=javabin&version=2} rid=127.0.0.1-4610 hits=68 status=0 QTime=17
[junit4] 2> 138095 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 138101 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[::1]:6/co|http://127.0.0.1:58906/co/collection1|[::1]:8/co&rid=127.0.0.1-4612&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173813652&isShard=true&wt=javabin} hits=68 status=0 QTime=2
[junit4] 2> 138107 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173813652&ids=132,111,133,144,112,146,148,105,118,141&isShard=true&shard.url=[::1]:6/co|http://127.0.0.1:58906/co/collection1|[::1]:8/co&rid=127.0.0.1-4612&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 138111 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=[::1]:6/co|http://127.0.0.1:58906/co/collection1|[::1]:8/co&sort=n_d1+desc&wt=javabin&version=2} rid=127.0.0.1-4612 hits=68 status=0 QTime=13
[junit4] 2> 138115 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 138125 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[::1]:6/co|http://127.0.0.1:58906/co/collection1&rid=127.0.0.1-4614&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173813673&isShard=true&wt=javabin} hits=68 status=0 QTime=4
[junit4] 2> 138134 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173813673&ids=101,3,136,138,6,1001,7,106,107,130&isShard=true&shard.url=[::1]:6/co|http://127.0.0.1:58906/co/collection1&rid=127.0.0.1-4614&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 138137 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=[::1]:6/co|http://127.0.0.1:58906/co/collection1&sort=n_d1+asc&wt=javabin&version=2} rid=127.0.0.1-4614 hits=68 status=0 QTime=17
[junit4] 2> 138139 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 140205 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[::1]:4/co|http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:8/co&rid=127.0.0.1-4616&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173813695&isShard=true&wt=javabin} hits=68 status=0 QTime=5
[junit4] 2> 140209 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173813695&ids=11,1,13,17,6,149,106,129,9,142&isShard=true&shard.url=[::1]:4/co|http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:8/co&rid=127.0.0.1-4616&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 140211 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=[::1]:4/co|http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:8/co&sort=n_td1+desc&wt=javabin&version=2} rid=127.0.0.1-4616 hits=68 status=0 QTime=2069
[junit4] 2> 140211 WARN (qtp452066416-873) [ x:collection1 ] o.a.s.c.S.SlowRequest slow: [collection1] webapp=/co path=/select params={q=*:*&shards=[::1]:4/co|http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:8/co&sort=n_td1+desc&wt=javabin&version=2} rid=127.0.0.1-4616 hits=68 status=0 QTime=2069
[junit4] 2> 140225 INFO (qtp1517042176-854) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 140241 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co 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:58906/co/collection1|[::1]:6/co&rid=127.0.0.1-4618&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173815790&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 140241 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173815790&ids=112,145,124,103,15,16,5,127,107,109&isShard=true&shard.url=http://127.0.0.1:58906/co/collection1|[::1]:6/co&rid=127.0.0.1-4618&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 140245 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=http://127.0.0.1:58906/co/collection1|[::1]:6/co&sort=n_td1+asc&wt=javabin&version=2} rid=127.0.0.1-4618 hits=68 status=0 QTime=7
[junit4] 2> 140251 INFO (qtp1517042176-855) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 140257 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co 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:58906/co/collection1|[::1]:6/co|[::1]:8/co&rid=127.0.0.1-4620&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173815806&isShard=true&wt=javabin} hits=68 status=0 QTime=2
[junit4] 2> 140269 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173815806&ids=111,124,146,102,148,104,105,119,120,142&isShard=true&shard.url=http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:8/co&rid=127.0.0.1-4620&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 140273 INFO (qtp452066416-873) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=http://127.0.0.1:58906/co/collection1|[::1]:6/co|[::1]:8/co&sort=n_l1+desc&wt=javabin&version=2} rid=127.0.0.1-4620 hits=68 status=0 QTime=21
[junit4] 2> 140276 INFO (qtp1517042176-856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 140281 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co 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:58906/co/collection1&rid=127.0.0.1-4622&rows=10&version=2&q=*:*&omitHeader=false&NOW=1603173815832&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 140284 INFO (qtp452066416-877) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1603173815832&ids=110,132,11,12,122,3,115,149,7,9&isShard=true&shard.url=http://127.0.0.1:58906/co/collection1&rid=127.0.0.1-4622&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 140287 INFO (qtp452066416-872) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&shards=http://127.0.0.1:58906/co/collection1&sort=n_l1+asc&wt=javabin&version=2} rid=127.0.0.1-4622 hits=68 status=0 QTime=9
[junit4] 2> 140289 INFO (qtp1517042176-857) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 140293 INFO (qtp452066416-876) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/co 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:58906/co/collection1|[::1]:6/co&rid=127.0.0.1-4624&rows=10&version=2&q=*:*&omitHeader=f
[...truncated too long message...]
ecutor-595-thread-9
[junit4] 2> 260794 INFO (closeThreadPool-635-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1da27d07{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 260795 INFO (closeThreadPool-635-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3aa069dd{/co,null,UNAVAILABLE}
[junit4] 2> 260795 INFO (closeThreadPool-635-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 260796 INFO (closeThreadPool-635-thread-7) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@77471d8b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:60860}
[junit4] 2> 260798 INFO (closeThreadPool-635-thread-7) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@49671267{/co,null,UNAVAILABLE}
[junit4] 2> 260799 INFO (closeThreadPool-635-thread-7) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 260803 INFO (closeThreadPool-635-thread-6) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3e74b4f6{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 260805 INFO (closeThreadPool-635-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@26341615{/co,null,UNAVAILABLE}
[junit4] 2> 260807 INFO (closeThreadPool-635-thread-6) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> ??? 20, 2020 5:05:36 PM com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[httpShardExecutor-595-thread-9,5,TGRP-TestDistributedSearch]
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@47714343[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@4d892833[Wrapped task = org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$508/0x0000000801041440@434ecff2]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@273c5c9[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
[junit4] 2> at __randomizedtesting.SeedInfo.seed([596B122F68597]:0)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825)
[junit4] 2> at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
[junit4] 2> at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
[junit4] 2> at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
[junit4] 2> at org.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:1128)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:830)
[junit4] 2>
[junit4] 2> 261925 INFO (closeThreadPool-635-thread-8) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7040b7c4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 261926 INFO (closeThreadPool-635-thread-8) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@564520f2{/co,null,UNAVAILABLE}
[junit4] 2> 261926 INFO (closeThreadPool-635-thread-8) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=596B122F68597 -Dtests.slow=true -Dtests.locale=hy -Dtests.timezone=Pacific/Bougainville -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 133s J1 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1176, name=httpShardExecutor-595-thread-9, state=RUNNABLE, group=TGRP-TestDistributedSearch]
[junit4] > at __randomizedtesting.SeedInfo.seed([596B122F68597:8851A96B8C0AE86F]:0)
[junit4] > Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@47714343[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@4d892833[Wrapped task = org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$508/0x0000000801041440@434ecff2]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@273c5c9[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
[junit4] > at __randomizedtesting.SeedInfo.seed([596B122F68597]:0)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2055)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:825)
[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:1128)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:830)
[junit4] 2> 261934 INFO (SUITE-TestDistributedSearch-seed#[596B122F68597]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {other_tl1=PostingsFormat(name=MockRandom), range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), SubjectTerms_mfacet=PostingsFormat(name=MockRandom), n_l1=FST50, a_n_tdt=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), multiDefault=FST50, intDefault=PostingsFormat(name=MockRandom), n_td1=PostingsFormat(name=MockRandom), n_d1=FST50, n_f1=PostingsFormat(name=MockRandom), range_facet_l=FST50, n_tl1=PostingsFormat(name=MockRandom), a_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), oddField_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_tf1=FST50, lowerfilt1and2=FST50, a_t=FST50, lowerfilt=FST50, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), timestamp=FST50, severity=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), foo_sev_enum=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), _root_=PostingsFormat(name=MockRandom), n_dt1=PostingsFormat(name=MockRandom), b_n_tdt=PostingsFormat(name=MockRandom), n_ti1=PostingsFormat(name=LuceneVarGapDocFreqInterval), rnd_b=PostingsFormat(name=LuceneVarGapDocFreqInterval), foo_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), n_tdt1=PostingsFormat(name=MockRandom), foo_d=PostingsFormat(name=LuceneVarGapDocFreqInterval), id_i1=PostingsFormat(name=MockRandom), range_facet_i_dv=FST50, foo_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{severity=DocValuesFormat(name=Lucene80), range_facet_l_dv=DocValuesFormat(name=Lucene80), foo_sev_enum=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Direct), intDvoDefault=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Direct), a_i_p=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1561, maxMBSortInHeap=7.0310038566610125, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=hy, timezone=Pacific/Bougainville
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 13.0.2 (64-bit)/cpus=6,threads=2,free=32044232,total=220971008
[junit4] 2> NOTE: All tests run in this JVM: [TestLeaderElectionWithEmptyReplica, TestCloudJSONFacetSKGEquiv, TestRequestStatusCollectionAPI, TestDistributedSearch]
[junit4] Completed [65/921 (1!)] on J1 in 134.23s, 1 test, 1 error <<< FAILURES!
[...truncated 50712 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1742/consoleText
[repro] Revision: 15d2bdd28b890b7bc478ca3ae8724eb68056d8d4
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[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 3477 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=596B122F68597 -Dtests.slow=true -Dtests.locale=hy -Dtests.timezone=Pacific/Bougainville -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 97 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.TestDistributedSearch
[repro] Exiting with code 0
[...truncated 98 lines...]