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/07 18:00:49 UTC
[JENKINS-Experimental-GC] Lucene-Solr-8.x-Windows
(64bit/jdk-14.0.1) - Build # 1705 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1705/
Java: 64bit/jdk-14.0.1 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Captured an uncaught exception in thread: Thread[id=17284, name=httpShardExecutor-11348-thread-5, state=RUNNABLE, group=TGRP-TestDistributedSearch]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=17284, name=httpShardExecutor-11348-thread-5, state=RUNNABLE, group=TGRP-TestDistributedSearch]
at __randomizedtesting.SeedInfo.seed([C40D6521BB10F747:4C595AFB15EC9ABF]:0)
Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@63996e3b[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@3a5271f3[Wrapped task = org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$528/0x0000000801099440@2fe9a7d8]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@63a4d601[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
at __randomizedtesting.SeedInfo.seed([C40D6521BB10F747]:0)
at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2057)
at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:827)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 15275 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> 1374809 INFO (SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1374809 INFO (SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1374809 INFO (SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\data-dir-91-001
[junit4] 2> 1374809 WARN (SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1374809 INFO (SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1374815 INFO (SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-9061")
[junit4] 2> 1374815 INFO (SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1375457 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\control\cores\collection1
[junit4] 2> 1375469 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1375469 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1375469 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1375469 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 1375470 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1375470 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1375470 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1375471 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7101d167{/,null,AVAILABLE}
[junit4] 2> 1375471 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@dc75af8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:59568}
[junit4] 2> 1375471 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.Server Started @1375508ms
[junit4] 2> 1375471 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=59568, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\control\cores}
[junit4] 2> 1375473 ERROR (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1375473 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1375473 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 1375473 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1375473 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1375473 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-07T17:15:38.766756900Z
[junit4] 2> 1375473 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\control\solr.xml
[junit4] 2> 1375479 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1375479 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1375480 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1375607 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1375609 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@45151e65[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1375609 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@45151e65[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1375613 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4b6edb9b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1375613 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4b6edb9b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1375615 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1375634 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1375634 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1375659 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
[junit4] 2> 1375669 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
[junit4] 2> 1375669 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
[junit4] 2> 1375672 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\control\cores
[junit4] 2> 1375672 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1375689 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 1375718 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1375849 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1375869 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 1375869 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
[junit4] 2> 1375869 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\control\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\control\cores\collection1\data\]
[junit4] 2> 1375873 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1375985 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1375985 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1375988 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1375988 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1375988 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.26288852325861034]
[junit4] 2> 1375989 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1376000 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1376058 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1376058 INFO (coreLoadExecutor-11204-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1679914108820914176
[junit4] 2> 1376060 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1376060 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1376060 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1376060 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1376060 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1376060 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1376062 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1376064 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1376064 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1376065 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1376889 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 1376895 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1376895 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1376895 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1376895 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 1376895 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1376895 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1376895 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1376895 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@db0ed63{/,null,AVAILABLE}
[junit4] 2> 1376899 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3fe2e8fe{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:59637}
[junit4] 2> 1376899 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.s.Server Started @1376934ms
[junit4] 2> 1376899 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=59637, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\shard0\cores}
[junit4] 2> 1376899 ERROR (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1376899 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1376899 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 1376899 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1376899 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1376899 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-07T17:15:40.192810400Z
[junit4] 2> 1376900 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\shard0\solr.xml
[junit4] 2> 1376903 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1376905 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1376906 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1377125 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1377126 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4eaba141[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377126 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4eaba141[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377129 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@ecdcd5c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377129 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@ecdcd5c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377130 WARN (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1377147 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1377147 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1377165 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
[junit4] 2> 1377174 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
[junit4] 2> 1377175 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
[junit4] 2> 1377177 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\shard0\cores
[junit4] 2> 1377177 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1377187 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 1377213 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1377329 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1377354 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 1377356 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3849b915
[junit4] 2> 1377356 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\shard0\cores\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_C40D6521BB10F747-001\tempDir-001\shard0\cores\collection1\data\]
[junit4] 2> 1377358 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1377526 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1377527 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1377529 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1377529 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1377590 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.26288852325861034]
[junit4] 2> 1377639 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1377657 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1377726 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1377727 INFO (coreLoadExecutor-11222-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1679914110570987520
[junit4] 2> 1377730 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1377730 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1377730 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1377730 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1377730 INFO (TEST-TestDistributedSearch.test-seed#[C40D6521BB10F747]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1377732 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1377732 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1377732 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1377732 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1377732 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1377732 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1377737 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1679914110576230400)} 0 3
[junit4] 2> 1377762 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1679914110583570432)} 0 21
[junit4] 2> 1377766 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1679914110608736256)]} 0 3
[junit4] 2> 1377769 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1679914110611881984)]} 0 2
[junit4] 2> 1377771 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1679914110616076288)]} 0 1
[junit4] 2> 1377773 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1679914110617124864)]} 0 0
[junit4] 2> 1377773 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1679914110619222016)]} 0 0
[junit4] 2> 1377773 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1679914110619222016)]} 0 0
[junit4] 2> 1377778 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1679914110619222017)]} 0 1
[junit4] 2> 1377778 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1679914110624464896)]} 0 0
[junit4] 2> 1377780 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1679914110624464896)]} 0 0
[junit4] 2> 1377780 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1679914110626562048)]} 0 0
[junit4] 2> 1377780 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1679914110626562048)]} 0 0
[junit4] 2> 1377781 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1679914110627610624)]} 0 0
[junit4] 2> 1377781 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1679914110627610624)]} 0 0
[junit4] 2> 1377781 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1679914110627610625)]} 0 0
[junit4] 2> 1377781 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1679914110627610625)]} 0 0
[junit4] 2> 1377781 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1679914110627610626)]} 0 0
[junit4] 2> 1377781 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1679914110627610626)]} 0 0
[junit4] 2> 1377781 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1679914110627610627)]} 0 0
[junit4] 2> 1377795 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1377796 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 11
[junit4] 2> 1377803 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1377803 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 6
[junit4] 2> 1377808 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1679914110651727872)]} 0 4
[junit4] 2> 1377811 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1679914110658019328)]} 0 2
[junit4] 2> 1377813 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1679914110661165056)]} 0 0
[junit4] 2> 1377820 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1679914110665359360)]} 0 4
[junit4] 2> 1377823 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1679914110669553664)]} 0 1
[junit4] 2> 1377824 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1679914110672699392)]} 0 0
[junit4] 2> 1377825 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1679914110673747968)]} 0 0
[junit4] 2> 1377827 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1679914110675845120)]} 0 0
[junit4] 2> 1377829 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1679914110676893696)]} 0 0
[junit4] 2> 1377830 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1679914110678990848)]} 0 0
[junit4] 2> 1377832 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1679914110681088000)]} 0 0
[junit4] 2> 1377833 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1679914110682136576)]} 0 0
[junit4] 2> 1377833 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1679914110682136576)]} 0 0
[junit4] 2> 1377833 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1679914110682136577)]} 0 0
[junit4] 2> 1377833 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1679914110682136577)]} 0 0
[junit4] 2> 1377836 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1679914110682136578)]} 0 0
[junit4] 2> 1377837 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1679914110685282304)]} 0 0
[junit4] 2> 1377837 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1679914110686330880)]} 0 0
[junit4] 2> 1377837 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1679914110686330880)]} 0 0
[junit4] 2> 1377841 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1679914110690525184)]} 0 0
[junit4] 2> 1377842 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1679914110691573760)]} 0 0
[junit4] 2> 1377842 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1679914110691573760)]} 0 0
[junit4] 2> 1377843 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1679914110692622336)]} 0 0
[junit4] 2> 1377845 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1679914110692622336)]} 0 0
[junit4] 2> 1377846 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1679914110695768064)]} 0 0
[junit4] 2> 1377846 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1679914110695768064)]} 0 0
[junit4] 2> 1377849 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1679914110698913792)]} 0 0
[junit4] 2> 1377850 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1679914110699962368)]} 0 0
[junit4] 2> 1377850 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1679914110699962368)]} 0 0
[junit4] 2> 1377853 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1679914110699962369)]} 0 0
[junit4] 2> 1377853 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1679914110703108096)]} 0 0
[junit4] 2> 1377853 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1679914110703108096)]} 0 0
[junit4] 2> 1377853 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1679914110703108097)]} 0 0
[junit4] 2> 1377857 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1679914110707302400)]} 0 0
[junit4] 2> 1377859 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1679914110709399552)]} 0 0
[junit4] 2> 1377860 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1679914110709399552)]} 0 0
[junit4] 2> 1377861 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1679914110710448128)]} 0 0
[junit4] 2> 1377861 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1679914110711496704)]} 0 0
[junit4] 2> 1377862 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1679914110712545280)]} 0 0
[junit4] 2> 1377863 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1679914110713593856)]} 0 0
[junit4] 2> 1377864 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1679914110714642432)]} 0 0
[junit4] 2> 1377864 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1679914110714642432)]} 0 0
[junit4] 2> 1377864 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1679914110714642433)]} 0 0
[junit4] 2> 1377867 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1679914110717788160)]} 0 0
[junit4] 2> 1377869 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1679914110719885312)]} 0 0
[junit4] 2> 1377869 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1679914110719885312)]} 0 0
[junit4] 2> 1377873 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1679914110724079616)]} 0 0
[junit4] 2> 1377873 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1679914110724079616)]} 0 0
[junit4] 2> 1377873 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1679914110724079617)]} 0 0
[junit4] 2> 1377878 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1679914110729322496)]} 0 0
[junit4] 2> 1377878 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1679914110729322496)]} 0 0
[junit4] 2> 1377878 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1679914110729322497)]} 0 0
[junit4] 2> 1377881 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1679914110729322497)]} 0 0
[junit4] 2> 1377881 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1679914110732468224)]} 0 0
[junit4] 2> 1377881 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1679914110732468224)]} 0 0
[junit4] 2> 1377881 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1679914110732468225)]} 0 0
[junit4] 2> 1377885 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1679914110732468225)]} 0 0
[junit4] 2> 1377885 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1679914110736662528)]} 0 0
[junit4] 2> 1377885 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1679914110736662528)]} 0 0
[junit4] 2> 1377885 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1679914110736662529)]} 0 0
[junit4] 2> 1377889 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1679914110740856832)]} 0 0
[junit4] 2> 1377889 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1679914110740856832)]} 0 0
[junit4] 2> 1377889 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1679914110740856833)]} 0 0
[junit4] 2> 1377889 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1679914110740856833)]} 0 0
[junit4] 2> 1377892 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1679914110742953984)]} 0 0
[junit4] 2> 1377893 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1679914110745051136)]} 0 0
[junit4] 2> 1377893 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1679914110745051136)]} 0 0
[junit4] 2> 1377893 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1679914110745051137)]} 0 0
[junit4] 2> 1377897 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1679914110745051137)]} 0 0
[junit4] 2> 1377897 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1679914110749245440)]} 0 0
[junit4] 2> 1377897 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1679914110749245440)]} 0 0
[junit4] 2> 1377900 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1679914110752391168)]} 0 0
[junit4] 2> 1377900 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1679914110752391168)]} 0 0
[junit4] 2> 1377900 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1679914110752391169)]} 0 0
[junit4] 2> 1377901 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1679914110753439744)]} 0 0
[junit4] 2> 1377901 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1679914110753439744)]} 0 0
[junit4] 2> 1377903 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1679914110755536896)]} 0 0
[junit4] 2> 1377904 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1679914110756585472)]} 0 0
[junit4] 2> 1377905 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1679914110757634048)]} 0 0
[junit4] 2> 1377906 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1679914110758682624)]} 0 0
[junit4] 2> 1377907 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1679914110759731200)]} 0 0
[junit4] 2> 1377909 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1679914110760779776)]} 0 0
[junit4] 2> 1377909 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1679914110761828352)]} 0 0
[junit4] 2> 1377911 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1679914110763925504)]} 0 0
[junit4] 2> 1377912 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1679914110764974080)]} 0 0
[junit4] 2> 1377912 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1679914110764974080)]} 0 0
[junit4] 2> 1377914 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1679914110766022656)]} 0 0
[junit4] 2> 1377915 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1679914110768119808)]} 0 0
[junit4] 2> 1377916 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1679914110768119808)]} 0 0
[junit4] 2> 1377916 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1679914110769168384)]} 0 0
[junit4] 2> 1377917 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1679914110770216960)]} 0 0
[junit4] 2> 1377918 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1679914110771265536)]} 0 0
[junit4] 2> 1377919 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1679914110772314112)]} 0 0
[junit4] 2> 1377919 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1679914110772314112)]} 0 0
[junit4] 2> 1377920 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1679914110773362688)]} 0 0
[junit4] 2> 1377921 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1679914110774411264)]} 0 0
[junit4] 2> 1377922 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1679914110774411264)]} 0 0
[junit4] 2> 1377923 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1679914110776508416)]} 0 0
[junit4] 2> 1377924 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1679914110777556992)]} 0 0
[junit4] 2> 1377925 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1679914110778605568)]} 0 0
[junit4] 2> 1377925 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1679914110778605568)]} 0 0
[junit4] 2> 1377925 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1679914110778605569)]} 0 0
[junit4] 2> 1377928 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1679914110778605569)]} 0 0
[junit4] 2> 1377928 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1679914110781751296)]} 0 0
[junit4] 2> 1377929 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1679914110782799872)]} 0 0
[junit4] 2> 1377929 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1679914110782799872)]} 0 0
[junit4] 2> 1377929 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1679914110782799873)]} 0 0
[junit4] 2> 1377929 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1679914110782799873)]} 0 0
[junit4] 2> 1377932 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1679914110785945600)]} 0 0
[junit4] 2> 1377933 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1679914110786994176)]} 0 0
[junit4] 2> 1377934 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1679914110788042752)]} 0 0
[junit4] 2> 1377935 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1679914110789091328)]} 0 0
[junit4] 2> 1377936 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1679914110790139904)]} 0 0
[junit4] 2> 1377937 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1679914110791188480)]} 0 0
[junit4] 2> 1377938 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1679914110792237056)]} 0 0
[junit4] 2> 1377940 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1679914110794334208)]} 0 0
[junit4] 2> 1377941 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1679914110795382784)]} 0 0
[junit4] 2> 1377942 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1679914110796431360)]} 0 0
[junit4] 2> 1377955 INFO (searcherExecutor-11206-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1377955 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 11
[junit4] 2> 1377962 INFO (searcherExecutor-11224-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1377962 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 1377967 ERROR (qtp132354293-17031) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
[junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:271)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:189)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:309)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2612)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:815)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:588)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1300)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1215)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value"
[junit4] 2> at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:68)
[junit4] 2> at java.base/java.lang.Integer.parseInt(Integer.java:652)
[junit4] 2> at java.base/java.lang.Integer.valueOf(Integer.java:983)
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
[junit4] 2> ... 38 more
[junit4] 2>
[junit4] 2> 1377967 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=non_numeric_value&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1377969 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1377969 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1377971 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1377971 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1377973 ERROR (qtp132354293-17030) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value"
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236)
[junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:274)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:189)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:309)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:214)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2612)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:815)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:588)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1300)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1215)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value"
[junit4] 2> at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:68)
[junit4] 2> at java.base/java.lang.Integer.parseInt(Integer.java:652)
[junit4] 2> at java.base/java.lang.Integer.valueOf(Integer.java:983)
[junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233)
[junit4] 2> ... 38 more
[junit4] 2>
[junit4] 2> 1377973 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&rows=non_numeric_value&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1377975 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1380007 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[::1]:6/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28308&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090941272&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1380009 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090941272&ids=143,122,133,102,5,16,105,117,106,142&isShard=true&shard.url=[::1]:6/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28308&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1380011 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:6/|http://127.0.0.1:59637/collection1&sort=n_ti1+desc&wt=javabin&version=2} rid=127.0.0.1-28308 hits=68 status=0 QTime=2032
[junit4] 2> 1380011 WARN (qtp1058486504-17051) [ x:collection1 ] o.a.s.c.S.SlowRequest slow: [collection1] webapp= path=/select params={q=*:*&shards=[::1]:6/|http://127.0.0.1:59637/collection1&sort=n_ti1+desc&wt=javabin&version=2} rid=127.0.0.1-28308 hits=68 status=0 QTime=2032
[junit4] 2> 1380013 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1380015 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[::1]:6/|http://127.0.0.1:59637/collection1|[::1]:8/&rid=127.0.0.1-28310&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943308&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1380017 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943308&ids=110,144,111,145,14,126,6,118,109,10&isShard=true&shard.url=[::1]:6/|http://127.0.0.1:59637/collection1|[::1]:8/&rid=127.0.0.1-28310&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1380018 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:6/|http://127.0.0.1:59637/collection1|[::1]:8/&sort=n_ti1+asc&wt=javabin&version=2} rid=127.0.0.1-28310 hits=68 status=0 QTime=4
[junit4] 2> 1380019 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1380022 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28312&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943313&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1380022 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943313&ids=11,100,102,103,147,17,106,7,107,8&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28312&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1380023 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_f1+desc&wt=javabin&version=2} rid=127.0.0.1-28312 hits=68 status=0 QTime=2
[junit4] 2> 1380024 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1380026 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28314&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943317&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1380027 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943317&ids=110,144,101,124,135,16,138,127,109,130&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28314&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1380029 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_f1+asc&wt=javabin&version=2} rid=127.0.0.1-28314 hits=68 status=0 QTime=4
[junit4] 2> 1380031 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1380033 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:8/&rid=127.0.0.1-28316&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943325&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1380033 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943325&ids=100,1,13,125,5,1001,118,108,109,130&isShard=true&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:8/&rid=127.0.0.1-28316&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1380034 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:8/&sort=n_tf1+desc&wt=javabin&version=2} rid=127.0.0.1-28316 hits=68 status=0 QTime=2
[junit4] 2> 1380035 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384106 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[::1]:8/|[::1]:4/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28318&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090943329&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384108 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090943329&ids=12,144,102,135,104,116,149,7,106,129&isShard=true&shard.url=[::1]:8/|[::1]:4/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28318&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1384110 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:8/|[::1]:4/|http://127.0.0.1:59637/collection1&sort=n_tf1+asc&wt=javabin&version=2} rid=127.0.0.1-28318 hits=68 status=0 QTime=4074
[junit4] 2> 1384111 WARN (qtp1058486504-17053) [ x:collection1 ] o.a.s.c.S.SlowRequest slow: [collection1] webapp= path=/select params={q=*:*&shards=[::1]:8/|[::1]:4/|http://127.0.0.1:59637/collection1&sort=n_tf1+asc&wt=javabin&version=2} rid=127.0.0.1-28318 hits=68 status=0 QTime=4074
[junit4] 2> 1384113 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384116 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[::1]:4/|http://127.0.0.1:59637/collection1|[::1]:6/&rid=127.0.0.1-28320&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947406&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384118 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947406&ids=143,100,102,14,126,149,139,141,130,10&isShard=true&shard.url=[::1]:4/|http://127.0.0.1:59637/collection1|[::1]:6/&rid=127.0.0.1-28320&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1384119 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[::1]:4/|http://127.0.0.1:59637/collection1|[::1]:6/&sort=n_d1+desc&wt=javabin&version=2} rid=127.0.0.1-28320 hits=68 status=0 QTime=4
[junit4] 2> 1384121 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384123 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28322&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947414&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384125 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947414&ids=122,111,13,136,16,127,138,128,117,1001&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28322&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1384125 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_d1+asc&wt=javabin&version=2} rid=127.0.0.1-28322 hits=68 status=0 QTime=4
[junit4] 2> 1384127 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384129 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:59637/collection1|[::1]:4/&rid=127.0.0.1-28324&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947420&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384130 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947420&ids=1,111,113,102,126,7,129,8,118,108&isShard=true&shard.url=http://127.0.0.1:59637/collection1|[::1]:4/&rid=127.0.0.1-28324&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1384132 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1|[::1]:4/&sort=n_td1+desc&wt=javabin&version=2} rid=127.0.0.1-28324 hits=68 status=0 QTime=3
[junit4] 2> 1384133 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384135 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28326&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947427&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384137 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947427&ids=132,11,133,145,146,103,6,117,1001,10&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28326&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1384138 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_td1+asc&wt=javabin&version=2} rid=127.0.0.1-28326 hits=68 status=0 QTime=4
[junit4] 2> 1384140 INFO (qtp132354293-17031) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384142 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28328&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947434&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384143 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947434&ids=143,111,123,113,102,116,6,128,118,9&isShard=true&shard.url=http://127.0.0.1:59637/collection1&rid=127.0.0.1-28328&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1384145 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1&sort=n_l1+desc&wt=javabin&version=2} rid=127.0.0.1-28328 hits=68 status=0 QTime=3
[junit4] 2> 1384145 INFO (qtp132354293-17032) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384148 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/&rid=127.0.0.1-28330&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947440&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384149 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947440&ids=110,134,124,148,17,105,138,139,140,120&isShard=true&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/&rid=127.0.0.1-28330&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1384150 INFO (qtp1058486504-17053) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1|[::1]:6/&sort=n_l1+asc&wt=javabin&version=2} rid=127.0.0.1-28330 hits=68 status=0 QTime=3
[junit4] 2> 1384151 INFO (qtp132354293-17030) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384153 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:4/&rid=127.0.0.1-28332&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947445&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384154 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1602090947445&ids=121,11,1,123,13,2,3,126,148,119&isShard=true&shard.url=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:4/&rid=127.0.0.1-28332&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1384156 INFO (qtp1058486504-17051) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:59637/collection1|[::1]:6/|[::1]:4/&sort=n_tl1+desc&wt=javabin&version=2} rid=127.0.0.1-28332 hits=68 status=0 QTime=4
[junit4] 2> 1384158 INFO (qtp132354293-17033) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1384160 INFO (qtp1058486504-17050) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[::1]:4/|http://127.0.0.1:59637/collection1&rid=127.0.0.1-28334&rows=10&version=2&q=*:*&omitHeader=false&NOW=1602090947452&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1384161 INFO (qtp1058486504-17054) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text
[...truncated too long message...]
eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2>
[junit4] 2> 1512353 INFO (closeThreadPool-11406-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@524cdd6d{/,null,UNAVAILABLE}
[junit4] 2> 1512354 INFO (closeThreadPool-11406-thread-6) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1512355 INFO (closeThreadPool-11406-thread-8) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@573370d1{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1512357 INFO (closeThreadPool-11406-thread-8) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3edfa643{/,null,UNAVAILABLE}
[junit4] 2> 1512357 INFO (closeThreadPool-11406-thread-8) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1512390 INFO (closeThreadPool-11406-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1512390 INFO (closeThreadPool-11406-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@57dc26e2: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@71c527e1
[junit4] 2> 1512392 INFO (closeThreadPool-11406-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1512392 INFO (closeThreadPool-11406-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4aadf777: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@604691d8
[junit4] 2> 1512392 INFO (closeThreadPool-11406-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1512392 INFO (closeThreadPool-11406-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f17dee5: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@336fe81e
[junit4] 2> 1512395 INFO (closeThreadPool-11406-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@194d594c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1512395 INFO (closeThreadPool-11406-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@60976e7e{/,null,UNAVAILABLE}
[junit4] 2> 1512395 INFO (closeThreadPool-11406-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1514338 INFO (closeThreadPool-11406-thread-7) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@65008f93{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1514338 INFO (closeThreadPool-11406-thread-7) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5c7d763d{/,null,UNAVAILABLE}
[junit4] 2> 1514339 INFO (closeThreadPool-11406-thread-7) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=C40D6521BB10F747 -Dtests.slow=true -Dtests.locale=en-IM -Dtests.timezone=Europe/Podgorica -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 140s J1 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=17284, name=httpShardExecutor-11348-thread-5, state=RUNNABLE, group=TGRP-TestDistributedSearch]
[junit4] > at __randomizedtesting.SeedInfo.seed([C40D6521BB10F747:4C595AFB15EC9ABF]:0)
[junit4] > Caused by: java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask@63996e3b[Not completed, task = java.util.concurrent.Executors$RunnableAdapter@3a5271f3[Wrapped task = org.eclipse.jetty.util.SocketAddressResolver$Async$$Lambda$528/0x0000000801099440@2fe9a7d8]] rejected from java.util.concurrent.ScheduledThreadPoolExecutor@63a4d601[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
[junit4] > at __randomizedtesting.SeedInfo.seed([C40D6521BB10F747]:0)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2057)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:827)
[junit4] > at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.delayedExecute(ScheduledThreadPoolExecutor.java:340)
[junit4] > at java.base/java.util.concurrent.ScheduledThreadPoolExecutor.schedule(ScheduledThreadPoolExecutor.java:562)
[junit4] > at org.eclipse.jetty.util.thread.ScheduledExecutorScheduler.schedule(ScheduledExecutorScheduler.java:122)
[junit4] > at org.eclipse.jetty.util.SocketAddressResolver$Async.lambda$resolve$1(SocketAddressResolver.java:157)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 1514341 INFO (SUITE-TestDistributedSearch-seed#[C40D6521BB10F747]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=en-IM, timezone=Europe/Podgorica
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14.0.1 (64-bit)/cpus=6,threads=2,free=136361488,total=530317312
[junit4] 2> NOTE: All tests run in this JVM: [IndexSizeTriggerSizeEstimationTest, TestTolerantSearch, BasicDistributedZk2Test, TestSolr4Spatial, HdfsTlogReplayBufferedWhileIndexingTest, TestUnifiedSolrHighlighterWithoutStoredId, DaemonStreamApiTest, TestZkAclsWithHadoopAuth, DocumentAnalysisRequestHandlerTest, CoreAdminCreateDiscoverTest, DistributedQueryElevationComponentTest, ChaosMonkeySafeLeaderTest, TestDistributedMap, V2StandaloneTest, ReplaceNodeTest, RulesTest, SolrTestCaseJ4Test, SpatialRPTFieldTypeTest, TestStressRecovery, ResponseLogComponentTest, TestBulkSchemaAPI, TestReloadDeadlock, TestHashQParserPlugin, JvmMetricsTest, TestMultiValuedNumericRangeQuery, InfixSuggestersTest, TestLuceneIndexBackCompat, CloudMLTQParserTest, TestCaffeineCache, TestFileDictionaryLookup, SpellingQueryConverterTest, TestRandomFlRTGCloud, TestSolrCloudSnapshots, TestCloudDeleteByQuery, FileBasedSpellCheckerTest, MultiDestinationAuditLoggerTest, TestOrdValues, HdfsRecoverLeaseTest, TestManagedSynonymFilterFactory, CreateRoutedAliasTest, TestLegacyNumericRangeQueryBuilder, TriggerSetPropertiesIntegrationTest, TestPKIAuthenticationPlugin, RankFieldTest, TestSolrConfigHandlerCloud, TestSubQueryTransformerCrossCore, SparseHLLTest, TestCloudSchemaless, DirectoryFactoryTest, OverseerStatusTest, TestPHPSerializedResponseWriter, ConnectionReuseTest, ClusterStateMockUtilTest, UnloadDistributedZkTest, SignificantTermsQParserPluginTest, TestConfigSetsAPIExclusivity, TestReplicationHandlerDiskOverFlow, LeaderVoteWaitTimeoutTest, ClassificationUpdateProcessorIntegrationTest, TestCoreAdminApis, TestSchemaSimilarityResource, TestRandomDVFaceting, TestTermsQParserPlugin, TestExportWriter, TestAtomicUpdateErrorCases, HdfsBasicDistributedZk2Test, TestFieldCacheSortRandom, CollectionReloadTest, ExplicitHLLTest, TestMacroExpander, FullSolrCloudDistribCmdsTest, TestCloudRecovery, DataDrivenBlockJoinTest, SplitByPrefixTest, TestLazySolrCluster, TestSolrJ, RoutingToNodesWithPropertiesTest, TestWaitForStateWithJettyShutdowns, LeaderElectionIntegrationTest, SolrCloudReportersTest, TestIntervalFaceting, EchoParamsTest, SuggestComponentContextFilterQueryTest, OpenExchangeRatesOrgProviderTest, ResponseHeaderTest, TestLegacyBM25SimilarityFactory, TestDistributedSearch]
[junit4] Completed [525/920 (1!)] on J1 in 140.25s, 1 test, 1 error <<< FAILURES!
[...truncated 46311 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1705/consoleText
[repro] Revision: 1c0f07ac03f0235adaf5c150f1c6656336e4282f
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestDistributedSearch
[repro] ant compile-test
[...truncated 3476 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC" -Dtests.seed=C40D6521BB10F747 -Dtests.slow=true -Dtests.locale=en-IM -Dtests.timezone=Europe/Podgorica -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 102 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.TestDistributedSearch
[repro] Exiting with code 0
[...truncated 98 lines...]
[JENKINS-Experimental-GC] Lucene-Solr-8.x-Windows
(64bit/jdk-14.0.1) - Build # 1706 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1706/
Java: 64bit/jdk-14.0.1 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
6 tests failed.
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602131293920 bcoll: 1602131296016
Stack Trace:
java.lang.AssertionError: acoll: 1602131293920 bcoll: 1602131296016
at __randomizedtesting.SeedInfo.seed([194761B52F3B355F:91135E6F81C758A7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602131295483 bcoll: 1602131296575
Stack Trace:
java.lang.AssertionError: acoll: 1602131295483 bcoll: 1602131296575
at __randomizedtesting.SeedInfo.seed([194761B52F3B355F:91135E6F81C758A7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602131295164 bcoll: 1602131296220
Stack Trace:
java.lang.AssertionError: acoll: 1602131295164 bcoll: 1602131296220
at __randomizedtesting.SeedInfo.seed([194761B52F3B355F:91135E6F81C758A7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602131299591 bcoll: 1602131303194
Stack Trace:
java.lang.AssertionError: acoll: 1602131299591 bcoll: 1602131303194
at __randomizedtesting.SeedInfo.seed([194761B52F3B355F:91135E6F81C758A7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602131294669 bcoll: 1602131295792
Stack Trace:
java.lang.AssertionError: acoll: 1602131294669 bcoll: 1602131295792
at __randomizedtesting.SeedInfo.seed([194761B52F3B355F:91135E6F81C758A7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.cloud.MultiThreadedOCPTest.test
Error Message:
acoll: 1602128698505 bcoll: 1602128698773
Stack Trace:
java.lang.AssertionError: acoll: 1602128698505 bcoll: 1602128698773
at __randomizedtesting.SeedInfo.seed([194761B52F3B355F:91135E6F81C758A7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 14837 lines...]
[junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest
[junit4] 2> 843000 INFO (SUITE-MultiThreadedOCPTest-seed#[194761B52F3B355F]-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> 843001 INFO (SUITE-MultiThreadedOCPTest-seed#[194761B52F3B355F]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\data-dir-61-001
[junit4] 2> 843001 INFO (SUITE-MultiThreadedOCPTest-seed#[194761B52F3B355F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 843002 INFO (SUITE-MultiThreadedOCPTest-seed#[194761B52F3B355F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 843002 INFO (SUITE-MultiThreadedOCPTest-seed#[194761B52F3B355F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 843003 INFO (SUITE-MultiThreadedOCPTest-seed#[194761B52F3B355F]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /tyw/dz
[junit4] 2> 843005 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 843005 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 843005 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 843008 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 843120 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer start zk server on port: 57310
[junit4] 2> 843120 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:57310
[junit4] 2> 843120 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57310
[junit4] 2> 843120 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57310
[junit4] 2> 843123 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 843130 INFO (zkConnectionManagerCallback-5876-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 843130 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 843136 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 843141 INFO (zkConnectionManagerCallback-5878-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 843141 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 843150 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 843160 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 843170 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 843178 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 843186 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 843190 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 843205 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 843216 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 843224 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 843230 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 843234 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 843242 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 843806 WARN (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 843806 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 843806 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 843806 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 843807 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 843807 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 843807 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 843808 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6641bcf9{/tyw/dz,null,AVAILABLE}
[junit4] 2> 843809 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5de4a7ed{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57320}
[junit4] 2> 843809 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.s.Server Started @843860ms
[junit4] 2> 843809 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/tyw/dz, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\tempDir-001/control/data, hostPort=57320, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\control-001\cores, replicaType=NRT}
[junit4] 2> 843810 ERROR (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 843810 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 843810 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 843810 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 843810 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 843810 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-08T03:44:17.027628900Z
[junit4] 2> 843811 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 843816 INFO (zkConnectionManagerCallback-5880-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 843817 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 843927 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 843927 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\control-001\solr.xml
[junit4] 2> 843930 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 843930 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 843930 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 844153 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 844155 WARN (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5665e2f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 844155 WARN (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5665e2f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 844158 WARN (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4d269e2a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 844158 WARN (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4d269e2a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 844159 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57310/solr
[junit4] 2> 844160 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 844165 INFO (zkConnectionManagerCallback-5890-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 844165 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 844269 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 844273 INFO (zkConnectionManagerCallback-5892-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 844273 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 844839 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57320_tyw%2Fdz
[junit4] 2> 844841 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.Overseer Overseer (id=72084062057791492-127.0.0.1:57320_tyw%2Fdz-n_0000000000) starting
[junit4] 2> 844860 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57320_tyw%2Fdz
[junit4] 2> 844860 INFO (OverseerStateUpdate-72084062057791492-127.0.0.1:57320_tyw%2Fdz-n_0000000000) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:57320_tyw%2Fdz
[junit4] 2> 844864 INFO (OverseerStateUpdate-72084062057791492-127.0.0.1:57320_tyw%2Fdz-n_0000000000) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 844870 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 844871 WARN (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] 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> 844890 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 844914 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 844920 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 844920 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 844921 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\control-001\cores
[junit4] 2> 844934 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 844938 INFO (zkConnectionManagerCallback-5909-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 844938 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 844939 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 844940 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57310/solr ready
[junit4] 2> 844943 INFO (qtp1937496892-8717) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:57320_tyw%252Fdz&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 844949 INFO (OverseerThreadFactory-5899-thread-1-processing-n:127.0.0.1:57320_tyw%2Fdz) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 845063 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 845064 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 846083 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 846095 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 846230 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 846249 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 846249 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 846250 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\control-001\cores\control_collection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\control-001\cores\control_collection_shard1_replica_n1\data\]
[junit4] 2> 846251 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 846254 WARN (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 846315 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 846316 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 846320 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 846320 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 846320 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=82.6533203125, floorSegmentMB=1.947265625, forceMergeDeletesPctAllowed=3.1444159390453197, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=38.85394886218386
[junit4] 2> 846323 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 846323 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 846323 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 846324 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1679953661306863616
[junit4] 2> 846325 INFO (searcherExecutor-5911-thread-1-processing-n:127.0.0.1:57320_tyw%2Fdz x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 846337 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 846337 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 846349 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 846349 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 846349 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57320/tyw/dz/control_collection_shard1_replica_n1/
[junit4] 2> 846350 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 846350 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:57320/tyw/dz/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 846350 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72084062057791492-core_node2-n_0000000000
[junit4] 2> 846356 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57320/tyw/dz/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 846461 INFO (zkCallback-5891-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 846461 INFO (zkCallback-5891-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 846464 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 846469 INFO (qtp1937496892-8716) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1405
[junit4] 2> 846475 INFO (qtp1937496892-8717) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 846572 INFO (zkCallback-5891-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 846572 INFO (zkCallback-5891-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 846572 INFO (zkCallback-5891-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 846574 INFO (qtp1937496892-8717) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:57320_tyw%252Fdz&wt=javabin&version=2} status=0 QTime=1631
[junit4] 2> 846575 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 846686 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 846691 INFO (zkConnectionManagerCallback-5920-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 846691 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 846692 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 846693 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57310/solr ready
[junit4] 2> 846693 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 846695 INFO (qtp1937496892-8717) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 846697 INFO (OverseerThreadFactory-5899-thread-2-processing-n:127.0.0.1:57320_tyw%2Fdz) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 846704 INFO (OverseerCollectionConfigSetProcessor-72084062057791492-127.0.0.1:57320_tyw%2Fdz-n_0000000000) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 846915 WARN (OverseerThreadFactory-5899-thread-2-processing-n:127.0.0.1:57320_tyw%2Fdz) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 846922 INFO (qtp1937496892-8717) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 846924 INFO (qtp1937496892-8717) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=228
[junit4] 2> 846924 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 846924 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 846925 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 846925 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 846925 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 846925 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 846925 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=3
[junit4] 2> 847480 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-1-001 of type NRT for shard2
[junit4] 2> 847481 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 847481 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 847481 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 847481 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 847482 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 847482 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 847482 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 847482 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@64be885a{/tyw/dz,null,AVAILABLE}
[junit4] 2> 847485 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@34984924{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57355}
[junit4] 2> 847485 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.Server Started @847535ms
[junit4] 2> 847485 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/tyw/dz, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\tempDir-001/jetty1, hostPort=57355, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-1-001\cores}
[junit4] 2> 847485 ERROR (closeThreadPool-5921-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 847485 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 847485 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 847485 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 847485 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 847485 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-08T03:44:20.702681800Z
[junit4] 2> 847486 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 847491 INFO (zkConnectionManagerCallback-5923-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 847491 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 847608 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 847608 INFO (closeThreadPool-5921-thread-1) [ ] 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.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-1-001\solr.xml
[junit4] 2> 847613 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 847613 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 847614 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 847730 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 847732 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5059dc4b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 847732 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5059dc4b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 847735 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@558d0358[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 847735 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@558d0358[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 847736 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57310/solr
[junit4] 2> 847737 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 847742 INFO (zkConnectionManagerCallback-5933-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 847743 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 847847 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 847865 INFO (zkConnectionManagerCallback-5935-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 847865 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 847875 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 847884 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.c.ZkController Publish node=127.0.0.1:57355_tyw%2Fdz as DOWN
[junit4] 2> 847890 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 847890 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57355_tyw%2Fdz
[junit4] 2> 847897 INFO (zkCallback-5891-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 847897 INFO (zkCallback-5934-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 847897 INFO (zkCallback-5919-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 847900 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 847901 WARN (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] 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> 847925 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 847944 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 847949 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 847949 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 847951 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57355_tyw%2Fdz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-1-001\cores
[junit4] 2> 847963 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:57355_tyw%2Fdz
[junit4] 2> 848052 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-2-001 of type NRT for shard1
[junit4] 2> 848053 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 848053 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 848054 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 848054 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 848054 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 848054 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 848054 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 848055 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f4c87ed{/tyw/dz,null,AVAILABLE}
[junit4] 2> 848057 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7f1b9f70{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57375}
[junit4] 2> 848057 INFO (closeThreadPool-5921-thread-1) [ ] o.e.j.s.Server Started @848107ms
[junit4] 2> 848057 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/tyw/dz, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\tempDir-001/jetty2, hostPort=57375, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-2-001\cores, replicaType=NRT}
[junit4] 2> 848057 ERROR (closeThreadPool-5921-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 848057 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 848057 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 848058 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 848058 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 848058 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-08T03:44:21.275209200Z
[junit4] 2> 848059 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 848066 INFO (zkConnectionManagerCallback-5945-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 848066 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 848180 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 848180 INFO (closeThreadPool-5921-thread-1) [ ] 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.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-2-001\solr.xml
[junit4] 2> 848186 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 848186 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 848186 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 848572 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 848575 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7a78bf34[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 848575 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7a78bf34[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 848579 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@417a380d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 848579 WARN (closeThreadPool-5921-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@417a380d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 848580 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57310/solr
[junit4] 2> 848582 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 848586 INFO (zkConnectionManagerCallback-5955-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 848586 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 848696 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 848702 INFO (zkConnectionManagerCallback-5957-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 848702 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 848713 INFO (OverseerCollectionConfigSetProcessor-72084062057791492-127.0.0.1:57320_tyw%2Fdz-n_0000000000) [n:127.0.0.1:57320_tyw%2Fdz ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 848713 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 848722 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.c.ZkController Publish node=127.0.0.1:57375_tyw%2Fdz as DOWN
[junit4] 2> 848726 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 848727 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57375_tyw%2Fdz
[junit4] 2> 848735 INFO (zkCallback-5891-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 848735 INFO (zkCallback-5934-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 848735 INFO (zkCallback-5919-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 848737 INFO (zkCallback-5956-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 848739 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 848739 WARN (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] 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> 848756 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 848764 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-3-001 of type NRT for shard2
[junit4] 2> 848765 WARN (closeThreadPool-5921-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 848765 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 848765 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 848765 INFO (closeThreadPool-5921-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 848766 INFO (closeThreadPool-5921-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 848766 INFO (closeThreadPool-5921-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 848766 INFO (closeThreadPool-5921-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 848767 INFO (closeThreadPool-5921-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@71ab3391{/tyw/dz,null,AVAILABLE}
[junit4] 2> 848769 INFO (closeThreadPool-5921-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@57c6dd58{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57409}
[junit4] 2> 848769 INFO (closeThreadPool-5921-thread-2) [ ] o.e.j.s.Server Started @848819ms
[junit4] 2> 848769 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/tyw/dz, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\tempDir-001/jetty3, hostPort=57409, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-3-001\cores}
[junit4] 2> 848769 ERROR (closeThreadPool-5921-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 848769 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 848769 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 848769 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 848769 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 848769 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-08T03:44:21.986193600Z
[junit4] 2> 848771 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 848775 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 848775 INFO (zkConnectionManagerCallback-5966-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 848775 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 848780 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 848781 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534
[junit4] 2> 848782 INFO (closeThreadPool-5921-thread-1) [n:127.0.0.1:57375_tyw%2Fdz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-2-001\cores
[junit4] 2> 848794 INFO (closeThreadPool-5921-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:57375_tyw%2Fdz
[junit4] 2> 848880 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 848880 INFO (closeThreadPool-5921-thread-2) [ ] 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.cloud.MultiThreadedOCPTest_194761B52F3B355F-001\shard-3-001\solr.xml
[junit4] 2> 848885 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 848885 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 848886 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@77b3c534, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 849071 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 849073 WARN (closeThreadPool-5921-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3e6a400f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 849073 WARN (closeThreadPool-5921-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3e6a400f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 849077 WARN (closeThreadPool-5921-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@375c006e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 849077 WARN (closeThreadPool-5921-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@375c006e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 849077 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57310/solr
[junit4] 2> 849079 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 849084 INFO (zkConnectionManagerCallback-5977-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 849084 INFO (closeThreadPool-5921-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 849189 INFO (closeThreadPool-5921-thread-2) [n:127.0.0.1:57409_tyw%2Fdz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 849194 INFO (zkConnectionManagerCallback-5979-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 849194 INFO (closeThreadPool-5921-thread-2) [n:127.0.0.1:57409_tyw%2Fdz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 849199 INFO (closeThreadPool-5921-thread-2) [n:127.0.0.1:57409_tyw%2Fdz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 849206 INFO (closeThreadPool-5921-thread-2) [n:127.0.0.1:57409_tyw%2Fdz ] o.a.s.c.ZkController Publish node=127.0.0.1:57409_tyw%2Fdz as DOWN
[junit4] 2> 849209 INFO (closeThreadPool-5921-thread-2) [n:127.0.0.1:57409_tyw%2Fdz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 849209 INFO (closeThreadPool-5921-thread-2) [n:127.0.0.1:57409_tyw%2Fdz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57409_tyw%2Fdz
[junit4] 2> 849212 INFO (zkCallback-5934-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 849213 INFO (zkCallback-5956-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 849213 INFO (zkCallback-5919-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 849213 INFO (zkCallback-5891-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 849213 INFO (zkCallback-5978-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 849215 INFO (closeThreadPool-5921-thread-2) [n:127.0.0.1:57409_tyw%2Fdz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 849215 WARN (closeThreadPool-5921-thread-2) [n:127.0.0.1:57409_tyw%2Fdz ] o.a.s.c.CoreContainer Not a
[...truncated too long message...]
olr/collections/collection1/collectionprops.json
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard2_0
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard1_1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard1_0
[junit4] 2> 3 /solr/collections/control_collection/terms/shard1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/terms/shard2_1
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 3 /solr/collections/ocptest_shardsplit2/collectionprops.json
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit2/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest3/terms/shard1
[junit4] 2> 2 /solr/collections/collection1/terms/shard1_0
[junit4] 2> 2 /solr/collections/collection1/terms/shard1_1
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard4
[junit4] 2> 2 /solr/collections/ocptest2/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard2
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard3
[junit4] 2> 2 /solr/collections/ocptest_shardsplit/terms/shard1
[junit4] 2> 2 /solr/collections/ocptest1/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 135 /solr/collections/ocptest_shardsplit2/state.json
[junit4] 2> 114 /solr/collections/collection1/state.json
[junit4] 2> 43 /solr/collections/ocptest_shardsplit/state.json
[junit4] 2> 41 /solr/collections/ocptest1/state.json
[junit4] 2> 33 /solr/collections/ocptest3/state.json
[junit4] 2> 33 /solr/collections/ocptest2/state.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 120 /solr/overseer/queue
[junit4] 2> 41 /solr/overseer/collection-queue-work
[junit4] 2> 31 /solr/collections
[junit4] 2> 23 /solr/live_nodes
[junit4] 2>
[junit4] 2> 142061 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:58681
[junit4] 2> 142061 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:58681
[junit4] 2> 142061 INFO (TEST-MultiThreadedOCPTest.test-seed#[194761B52F3B355F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 58681
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MultiThreadedOCPTest -Dtests.method=test -Dtests.seed=194761B52F3B355F -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=sv -Dtests.timezone=Australia/Perth -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 128s J0 | MultiThreadedOCPTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: acoll: 1602131299591 bcoll: 1602131303194
[junit4] > at __randomizedtesting.SeedInfo.seed([194761B52F3B355F:91135E6F81C758A7]:0)
[junit4] > at org.apache.solr.cloud.MultiThreadedOCPTest.testFillWorkQueue(MultiThreadedOCPTest.java:113)
[junit4] > at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:68)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.MultiThreadedOCPTest_194761B52F3B355F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), _root_=PostingsFormat(name=MockRandom), id=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene80), intDefault=DocValuesFormat(name=Lucene80), id_i1=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene80), intDvoDefault=DocValuesFormat(name=Lucene80), range_facet_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1661, maxMBSortInHeap=5.0307884401526985, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=sv, timezone=Australia/Perth
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14.0.1 (64-bit)/cpus=6,threads=1,free=287309824,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [MultiThreadedOCPTest]
[junit4] Completed [5/5 (5!)] on J0 in 141.83s, 1 test, 1 failure <<< FAILURES!
[...truncated 17 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1599: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1126: There were test failures: 5 suites, 5 tests, 5 failures [seed: 194761B52F3B355F]
Total time: 2 minutes 29 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 5/5 failed: org.apache.solr.cloud.MultiThreadedOCPTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>exit 0
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Archiving artifacts
[Java] Sleeping for 5 seconds due to JENKINS-32191...
[Java] Parsing console log (workspace: 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows')
[Java] Successfully parsed console log
[Java] -> found 112 issues (skipped 103 duplicates)
[Java] Post processing issues on 'Windows VBOX' with source code encoding 'UTF-8'
[Java] Creating SCM blamer to obtain author and commit information for affected files
[Java] Creating SCM miner to obtain statistics for affected repository files
[Java] Resolving file names for all issues in source directory 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows'
[Java] -> resolved paths in source directory (53 found, 0 not found)
[Java] Resolving module names from module definitions (build.xml, pom.xml, or Manifest.mf files)
[Java] -> resolved module names for 112 issues
[Java] Resolving package names (or namespaces) by parsing the affected files
[Java] -> resolved package names of 53 affected files
[Java] No filter has been set, publishing all 112 issues
[Java] Creating fingerprints for all affected code blocks to track issues over different builds
[Java] -> created fingerprints for 112 issues (skipped 0 issues)
[Java] Copying affected files to Jenkins' build folder '/home/jenkins/jobs/Lucene-Solr-8.x-Windows/builds/1706/files-with-issues'
[Java] -> 53 copied, 0 not in workspace, 0 not-found, 0 with I/O error
[Java] Using reference build 'Lucene-Solr-8.x-Windows #1705' to compute new, fixed, and outstanding issues
[Java] Issues delta (vs. reference build): outstanding: 112, new: 0, fixed: 0
[Java] No quality gates have been set - skipping
[Java] Health report is disabled - skipping
[Java] Created analysis result for 112 issues (found 0 new issues, fixed 0 issues)
[Java] Attaching ResultAction with ID 'java' to run 'Lucene-Solr-8.x-Windows #1706'.
[Checks API] No suitable checks publisher found.
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
[Checks API] No suitable checks publisher found.
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2