lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-BadApples-NightlyTests-7.x - Build # 16 - Failure
Date Wed, 04 Jul 2018 06:58:54 GMT
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-NightlyTests-7.x/16/

No tests ran.

Build Log:
[...truncated 9448 lines...]
   [junit4] Suite: org.apache.lucene.search.TestInetAddressRangeQueries
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestInetAddressRangeQueries -Dtests.method=testRandomBig -Dtests.seed=4D81F901C6775772 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/test-data/enwiki.random.lines.txt -Dtests.locale=et -Dtests.timezone=Asia/Kashgar -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR    260s J0 | TestInetAddressRangeQueries.testRandomBig <<<
   [junit4]    > Throwable #1: java.lang.OutOfMemoryError: Java heap space
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([4D81F901C6775772:CAD6848E572E2BF2]:0)
   [junit4]    > 	at java.util.Arrays.copyOf(Arrays.java:3332)
   [junit4]    > 	at java.lang.AbstractStringBuilder.ensureCapacityInternal(AbstractStringBuilder.java:124)
   [junit4]    > 	at java.lang.AbstractStringBuilder.append(AbstractStringBuilder.java:448)
   [junit4]    > 	at java.lang.StringBuilder.append(StringBuilder.java:136)
   [junit4]    > 	at org.apache.lucene.store.MockIndexInputWrapper.<init>(MockIndexInputWrapper.java:40)
   [junit4]    > 	at org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:774)
   [junit4]    > 	at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:100)
   [junit4]    > 	at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:100)
   [junit4]    > 	at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:100)
   [junit4]    > 	at org.apache.lucene.store.Directory.openChecksumInput(Directory.java:121)
   [junit4]    > 	at org.apache.lucene.util.bkd.BKDWriter.verifyChecksum(BKDWriter.java:1375)
   [junit4]    > 	at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1799)
   [junit4]    > 	at org.apache.lucene.util.bkd.BKDWriter.finish(BKDWriter.java:1008)
   [junit4]    > 	at org.apache.lucene.index.RandomCodec$1$1.writeField(RandomCodec.java:141)
   [junit4]    > 	at org.apache.lucene.codecs.PointsWriter.mergeOneField(PointsWriter.java:62)
   [junit4]    > 	at org.apache.lucene.codecs.PointsWriter.merge(PointsWriter.java:186)
   [junit4]    > 	at org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.merge(Lucene60PointsWriter.java:144)
   [junit4]    > 	at org.apache.lucene.codecs.asserting.AssertingPointsFormat$AssertingPointsWriter.merge(AssertingPointsFormat.java:142)
   [junit4]    > 	at org.apache.lucene.index.SegmentMerger.mergePoints(SegmentMerger.java:188)
   [junit4]    > 	at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:137)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4412)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4061)
   [junit4]    > 	at org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2163)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1996)
   [junit4]    > 	at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1947)
   [junit4]    > 	at org.apache.lucene.search.BaseRangeFieldQueryTestCase.verify(BaseRangeFieldQueryTestCase.java:202)
   [junit4]    > 	at org.apache.lucene.search.BaseRangeFieldQueryTestCase.doTestRandom(BaseRangeFieldQueryTestCase.java:160)
   [junit4]    > 	at org.apache.lucene.search.BaseRangeFieldQueryTestCase.testRandomBig(BaseRangeFieldQueryTestCase.java:75)
   [junit4]    > 	at org.apache.lucene.search.TestInetAddressRangeQueries.testRandomBig(TestInetAddressRangeQueries.java:83)
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/lucene/build/misc/test/J0/temp/lucene.search.TestInetAddressRangeQueries_4D81F901C6775772-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {id=BlockTreeOrds(blocksize=128)}, docValues:{ipRangeField=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=263, maxMBSortInHeap=7.948178509287246, sim=RandomSimilarity(queryNorm=true): {}, locale=et, timezone=Asia/Kashgar
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=4,threads=1,free=149059520,total=231735296
   [junit4]   2> NOTE: All tests run in this JVM: [TestFSTsMisc, TestInetAddressPoint, TestMultiPassIndexSplitter, TestIndexSplitter, TestInetAddressRangeQueries]
   [junit4] Completed [13/13 (1!)] on J0 in 303.70s, 4 tests, 1 error <<< FAILURES!

[...truncated 1 lines...]
   [junit4] JVM J0: stdout was not empty, see: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/lucene/build/misc/test/temp/junit4-J0-20180704_032837_4371338784877383125952.sysout
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: Java heap space
   [junit4] Dumping heap to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/heapdumps/java_pid22423.hprof ...
   [junit4] Heap dump file created [574397744 bytes in 7.204 secs]
   [junit4] <<< JVM J0: EOF ----

[...truncated 4876 lines...]
   [junit4] Suite: org.apache.solr.search.TestRecovery
   [junit4]   2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_91DD4EE09180D192-001/init-core-data-001
   [junit4]   2> 4403963 WARN  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16
   [junit4]   2> 4403975 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 4403977 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 4403988 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 4403988 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
   [junit4]   2> 4403988 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4403988 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4404228 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4404281 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4404666 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4405211 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 4405211 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 4405256 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4405291 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4405291 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4405299 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4405551 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4405611 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4406011 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4406029 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 4406029 INFO  (coreLoadExecutor-5995-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@4cada4f8
   [junit4]   2> 4406029 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4406029 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_91DD4EE09180D192-001/init-core-data-001/]
   [junit4]   2> 4406044 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=1.7822265625, floorSegmentMB=1.40625, forceMergeDeletesPctAllowed=21.279967835114636, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8771380216212127, reclaimDeletesWeight=0.8025729024518511
   [junit4]   2> 4406072 WARN  (coreLoadExecutor-5995-thread-1) [    x:collection1] 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> 4406271 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 4406271 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 4406272 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4406272 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4406274 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1141091910, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 4406293 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@27a5f9d7[collection1] main]
   [junit4]   2> 4406293 WARN  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 4406294 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4406295 INFO  (searcherExecutor-5996-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@27a5f9d7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4406295 INFO  (coreLoadExecutor-5995-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1605039705319014400
   [junit4]   2> 4406330 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4406367 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCleanShutdown
   [junit4]   2> 4406381 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4406383 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5981bc8c[collection1] realtime]
   [junit4]   2> 4406383 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 1
   [junit4]   2> 4406383 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1605039705411289088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4406383 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@56dfb43f commitCommandVersion:1605039705411289088
   [junit4]   2> 4406419 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@a6ccd64[collection1] main]
   [junit4]   2> 4406419 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4406421 INFO  (searcherExecutor-5996-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a6ccd64[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4406421 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 37
   [junit4]   2> 4406457 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[E1 (1605039705471057920)]} 0 16
   [junit4]   2> 4406457 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[E2 (1605039705488883712)]} 0 0
   [junit4]   2> 4406457 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2136475698
   [junit4]   2> 4406458 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 4406458 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6624be01: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@344a1c97
   [junit4]   2> 4406492 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 4406492 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@427f33b4: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@36eb059c
   [junit4]   2> 4406511 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 4406511 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2133e85f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@39dee1e2
   [junit4]   2> 4406527 INFO  (coreCloseExecutor-6001-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@172c1514
   [junit4]   2> 4406527 INFO  (coreCloseExecutor-6001-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=172c1514
   [junit4]   2> 4406527 INFO  (coreCloseExecutor-6001-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12800a76: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@179393fd
   [junit4]   2> 4406534 INFO  (coreCloseExecutor-6001-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 4406534 INFO  (coreCloseExecutor-6001-thread-1) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@56dfb43f commitCommandVersion:0
   [junit4]   2> 4406699 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4406810 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4406881 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4407254 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4407710 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 4407710 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 4407764 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4407800 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4407800 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4407820 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4407971 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4408027 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4408425 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4408447 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 4408460 INFO  (coreLoadExecutor-6011-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@4cada4f8
   [junit4]   2> 4408460 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4408460 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_91DD4EE09180D192-001/init-core-data-001/]
   [junit4]   2> 4408463 WARN  (coreLoadExecutor-6011-thread-1) [    x:collection1] 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> 4408644 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 4408644 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 4408646 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4408646 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4408663 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=1.7822265625, floorSegmentMB=1.40625, forceMergeDeletesPctAllowed=21.279967835114636, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8771380216212127, reclaimDeletesWeight=0.8025729024518511
   [junit4]   2> 4408700 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ad7b512[collection1] main]
   [junit4]   2> 4408700 WARN  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 4408701 INFO  (coreLoadExecutor-6011-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4408702 INFO  (searcherExecutor-6012-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6ad7b512[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.5.0):C2)))}
   [junit4]   2> 4408737 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 4408739 INFO  (TEST-TestRecovery.testCleanShutdown-seed#[91DD4EE09180D192]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCleanShutdown
   [junit4]   2> 4408773 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testTruncatedLog
   [junit4]   2> 4408774 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4408790 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40b04d6c[collection1] realtime]
   [junit4]   2> 4408790 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 16
   [junit4]   2> 4408791 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1605039707936260096,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4408791 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fd238e commitCommandVersion:1605039707936260096
   [junit4]   2> 4408863 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ccf5552[collection1] main]
   [junit4]   2> 4408863 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4408865 INFO  (searcherExecutor-6012-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6ccf5552[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4408865 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 74
   [junit4]   2> 4408900 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[F1 (1605039708049506304)]} 0 34
   [junit4]   2> 4408901 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[F2 (1605039708050554880)]} 0 0
   [junit4]   2> 4408915 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[F3 (1605039708066283520)]} 0 13
   [junit4]   2> 4408915 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1042841465
   [junit4]   2> 4408915 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 4408915 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4fdfe18: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6886beaf
   [junit4]   2> 4408972 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 4408972 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60695603: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@36eb059c
   [junit4]   2> 4408975 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 4408975 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4b06e7d1: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@39dee1e2
   [junit4]   2> 4409045 INFO  (coreCloseExecutor-6017-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@251153c3
   [junit4]   2> 4409045 INFO  (coreCloseExecutor-6017-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=251153c3
   [junit4]   2> 4409045 INFO  (coreCloseExecutor-6017-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2a0b77ac: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@166f43ca
   [junit4]   2> 4409193 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4409400 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4409611 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4409962 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4410967 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 4410967 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 4411121 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4411161 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4411161 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4411175 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4411305 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4411340 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4411701 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4411737 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 4411737 INFO  (coreLoadExecutor-6027-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@4cada4f8
   [junit4]   2> 4411738 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4411738 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_91DD4EE09180D192-001/init-core-data-001/]
   [junit4]   2> 4411753 WARN  (coreLoadExecutor-6027-thread-1) [    x:collection1] 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> 4411936 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 4411936 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 4411938 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4411938 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4411955 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=1.7822265625, floorSegmentMB=1.40625, forceMergeDeletesPctAllowed=21.279967835114636, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8771380216212127, reclaimDeletesWeight=0.8025729024518511
   [junit4]   2> 4411956 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a0d9f18[collection1] main]
   [junit4]   2> 4411956 WARN  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 4411957 INFO  (coreLoadExecutor-6027-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4411957 INFO  (searcherExecutor-6028-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5a0d9f18[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4411973 WARN  (recoveryExecutor-6030-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.UpdateLog Starting log replay tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_91DD4EE09180D192-001/init-core-data-001/tlog/tlog.0000000000000000003.1605039708049506304 refcount=1} active=false starting pos=0 inSortedOrder=false
   [junit4]   2> 4411975 INFO  (recoveryExecutor-6030-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.UpdateLog Ignoring exception matching OutOfBoundsException
   [junit4]   2> 4412475 INFO  (recoveryExecutor-6030-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4412475 INFO  (recoveryExecutor-6030-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5e1762ce commitCommandVersion:0
   [junit4]   2> 4412712 INFO  (recoveryExecutor-6030-thread-1-processing-x:collection1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a820511[collection1] main]
   [junit4]   2> 4412712 INFO  (recoveryExecutor-6030-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4412720 INFO  (searcherExecutor-6028-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7a820511[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.5.0):C3)))}
   [junit4]   2> 4412721 INFO  (recoveryExecutor-6030-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[F1 (1605039708049506304), F2 (1605039708050554880), F3 (1605039708066283520)]} 0 747
   [junit4]   2> 4412721 WARN  (recoveryExecutor-6030-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=3 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
   [junit4]   2> 4412721 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 4412778 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[F4 (1)]} 0 55
   [junit4]   2> 4412779 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[F5 (2)]} 0 0
   [junit4]   2> 4412779 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[F6 (3)]} 0 0
   [junit4]   2> 4412780 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&getVersions=3&wt=xml} status=0 QTime=0
   [junit4]   2> 4412799 INFO  (TEST-TestRecovery.testTruncatedLog-seed#[91DD4EE09180D192]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testTruncatedLog
   [junit4]   2> 4412833 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.SolrTestCaseJ4 ###Starting stressLogReplay
   [junit4]   2> 4412847 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4412848 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@480fd219[collection1] realtime]
   [junit4]   2> 4412848 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 0
   [junit4]   2> 4412848 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1605039712190332928,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4412848 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5e1762ce commitCommandVersion:1605039712190332928
   [junit4]   2> 4412866 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f07c953[collection1] main]
   [junit4]   2> 4412867 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4412872 INFO  (searcherExecutor-6028-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2f07c953[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4412872 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 24
   [junit4]   2> 4412874 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4815 (1605039712216547328)]} 0 1
   [junit4]   2> 4412874 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6031 (1605039712217595904)]} 0 0
   [junit4]   2> 4412875 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8002 (1605039712217595905)]} 0 0
   [junit4]   2> 4412875 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1952 (1605039712218644480)]} 0 0
   [junit4]   2> 4412875 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7139 (1605039712218644481)]} 0 0
   [junit4]   2> 4412875 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5919 (1605039712218644482)]} 0 0
   [junit4]   2> 4412876 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6672 (1605039712219693056)]} 0 0
   [junit4]   2> 4412876 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5505 (1605039712219693057)]} 0 0
   [junit4]   2> 4412876 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7213 (1605039712219693058)]} 0 0
   [junit4]   2> 4412876 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5628 (1605039712219693059)]} 0 0
   [junit4]   2> 4412877 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[285 (1605039712220741632)]} 0 0
   [junit4]   2> 4412877 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6903 (1605039712220741633)]} 0 0
   [junit4]   2> 4412877 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3923 (1605039712220741634)]} 0 0
   [junit4]   2> 4412877 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4698 (1605039712220741635)]} 0 0
   [junit4]   2> 4412894 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[6031 (-1605039712221790208)]} 0 16
   [junit4]   2> 4412895 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4698 (1605039712239616000)]} 0 0
   [junit4]   2> 4412896 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1952 (1605039712240664576)]} 0 0
   [junit4]   2> 4412896 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7749 (1605039712240664577)]} 0 0
   [junit4]   2> 4412896 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1381 (1605039712240664578)]} 0 0
   [junit4]   2> 4412896 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1147 (1605039712240664579)]} 0 0
   [junit4]   2> 4412897 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[763 (1605039712241713152)]} 0 0
   [junit4]   2> 4412897 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4691 (1605039712241713153)]} 0 0
   [junit4]   2> 4412897 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6903 (1605039712241713154)]} 0 0
   [junit4]   2> 4412898 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5873 (1605039712242761728)]} 0 0
   [junit4]   2> 4412898 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4036 (1605039712242761729)]} 0 0
   [junit4]   2> 4412949 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[4036 (-1605039712296239104)]} 0 50
   [junit4]   2> 4412949 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[379 (1605039712296239105)]} 0 0
   [junit4]   2> 4412949 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6651 (1605039712296239106)]} 0 0
   [junit4]   2> 4412950 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5919 (1605039712297287680)]} 0 0
   [junit4]   2> 4412950 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4815 (1605039712297287681)]} 0 0
   [junit4]   2> 4412950 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8271 (1605039712297287682)]} 0 0
   [junit4]   2> 4412951 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5628 (1605039712298336256)]} 0 0
   [junit4]   2> 4413341 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@58cf32b3[collection1] realtime]
   [junit4]   2> 4413344 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c7e6f3a[collection1] realtime]
   [junit4]   2> 4413344 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:0 (-1605039712298336257)} 0 393
   [junit4]   2> 4413379 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9661 (1605039712746078208)]} 0 0
   [junit4]   2> 4413379 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5842 (1605039712747126784)]} 0 0
   [junit4]   2> 4413380 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5873 (1605039712747126785)]} 0 0
   [junit4]   2> 4413380 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[763 (1605039712748175360)]} 0 0
   [junit4]   2> 4413381 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[540 (1605039712748175361)]} 0 0
   [junit4]   2> 4413381 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1428 (1605039712749223936)]} 0 0
   [junit4]   2> 4413381 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1336 (1605039712749223937)]} 0 0
   [junit4]   2> 4413381 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4121 (1605039712749223938)]} 0 0
   [junit4]   2> 4413381 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8114 (1605039712749223939)]} 0 0
   [junit4]   2> 4413382 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5304 (1605039712750272512)]} 0 0
   [junit4]   2> 4413415 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[285 (1605039712784875520)]} 0 0
   [junit4]   2> 4413415 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[670 (1605039712784875521)]} 0 0
   [junit4]   2> 4413415 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[5873 (-1605039712784875522)]} 0 0
   [junit4]   2> 4413416 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1336 (1605039712785924096)]} 0 0
   [junit4]   2> 4413416 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7261 (1605039712785924097)]} 0 0
   [junit4]   2> 4413541 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@72092e02[collection1] realtime]
   [junit4]   2> 4413599 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@68df3033[collection1] realtime]
   [junit4]   2> 4413600 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:133 (-1605039712785924098)} 0 183
   [junit4]   2> 4413601 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[409 (1605039712978862080)]} 0 0
   [junit4]   2> 4413601 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8215 (1605039712979910656)]} 0 0
   [junit4]   2> 4413601 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5919 (1605039712979910657)]} 0 0
   [junit4]   2> 4413744 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1381 (1605039713129857024)]} 0 0
   [junit4]   2> 4413745 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6920 (1605039713130905600)]} 0 0
   [junit4]   2> 4413745 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6903 (1605039713130905601)]} 0 0
   [junit4]   2> 4413746 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5489 (1605039713130905602)]} 0 0
   [junit4]   2> 4413746 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3973 (1605039713131954176)]} 0 0
   [junit4]   2> 4413746 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4698 (1605039713131954177)]} 0 0
   [junit4]   2> 4413746 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2637 (1605039713131954178)]} 0 0
   [junit4]   2> 4413747 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9078 (1605039713133002752)]} 0 0
   [junit4]   2> 4413747 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5281 (1605039713133002753)]} 0 0
   [junit4]   2> 4413747 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3690 (1605039713133002754)]} 0 0
   [junit4]   2> 4413748 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8271 (1605039713134051328)]} 0 0
   [junit4]   2> 4413818 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4815 (1605039713206403072)]} 0 0
   [junit4]   2> 4413818 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7139 (1605039713207451648)]} 0 0
   [junit4]   2> 4413818 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3449 (1605039713207451649)]} 0 0
   [junit4]   2> 4413819 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[212 (1605039713207451650)]} 0 0
   [junit4]   2> 4413819 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1952 (1605039713208500224)]} 0 0
   [junit4]   2> 4413819 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6476 (1605039713208500225)]} 0 0
   [junit4]   2> 4413819 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7918 (1605039713208500226)]} 0 0
   [junit4]   2> 4413820 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1163 (1605039713209548800)]} 0 0
   [junit4]   2> 4413820 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9746 (1605039713209548801)]} 0 0
   [junit4]   2> 4413820 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8063 (1605039713209548802)]} 0 0
   [junit4]   2> 4413820 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1045 (1605039713209548803)]} 0 0
   [junit4]   2> 4413821 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2985 (1605039713210597376)]} 0 0
   [junit4]   2> 4413890 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2699 (1605039713210597377)]} 0 69
   [junit4]   2> 4413891 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9078 (1605039713283997696)]} 0 0
   [junit4]   2> 4413891 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7759 (1605039713283997697)]} 0 0
   [junit4]   2> 4413892 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5919 (1605039713285046272)]} 0 0
   [junit4]   2> 4413892 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[4815 (-1605039713285046273)]} 0 0
   [junit4]   2> 4413893 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9078 (1605039713286094848)]} 0 0
   [junit4]   2> 4413893 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7213 (1605039713286094849)]} 0 0
   [junit4]   2> 4413893 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3081 (1605039713286094850)]} 0 0
   [junit4]   2> 4413894 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[4691 (-1605039713287143424)]} 0 0
   [junit4]   2> 4413894 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7918 (1605039713287143425)]} 0 0
   [junit4]   2> 4414073 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5304 (1605039713423458304)]} 0 126
   [junit4]   2> 4414074 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1334 (1605039713475887104)]} 0 0
   [junit4]   2> 4414074 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4563 (1605039713475887105)]} 0 0
   [junit4]   2> 4414074 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9218 (1605039713475887106)]} 0 0
   [junit4]   2> 4414075 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8114 (1605039713476935680)]} 0 0
   [junit4]   2> 4414075 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4725 (1605039713476935681)]} 0 0
   [junit4]   2> 4414076 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9661 (1605039713476935682)]} 0 0
   [junit4]   2> 4414076 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[0 (1605039713477984256)]} 0 0
   [junit4]   2> 4414076 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[318 (1605039713477984257)]} 0 0
   [junit4]   2> 4414076 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[5919 (-1605039713477984258)]} 0 0
   [junit4]   2> 4414077 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5505 (1605039713479032832)]} 0 0
   [junit4]   2> 4414130 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1334 (1605039713534607360)]} 0 53
   [junit4]   2> 4414131 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[670 (1605039713535655936)]} 0 0
   [junit4]   2> 4414131 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5628 (1605039713535655937)]} 0 0
   [junit4]   2> 4414132 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5208 (1605039713536704512)]} 0 0
   [junit4]   2> 4414132 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3690 (1605039713536704513)]} 0 0
   [junit4]   2> 4414133 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4698 (1605039713536704514)]} 0 0
   [junit4]   2> 4414133 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7492 (1605039713537753088)]} 0 0
   [junit4]   2> 4414133 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8148 (1605039713537753089)]} 0 0
   [junit4]   2> 4414133 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2583 (1605039713537753090)]} 0 0
   [junit4]   2> 4414134 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1381 (1605039713538801664)]} 0 0
   [junit4]   2> 4414199 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[379 (1605039713606959104)]} 0 0
   [junit4]   2> 4414200 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6222 (1605039713606959105)]} 0 0
   [junit4]   2> 4414200 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5489 (1605039713608007680)]} 0 0
   [junit4]   2> 4414200 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3081 (1605039713608007681)]} 0 0
   [junit4]   2> 4414200 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1642 (1605039713608007682)]} 0 0
   [junit4]   2> 4414201 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[6222 (-1605039713609056256)]} 0 0
   [junit4]   2> 4414201 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1642 (1605039713609056257)]} 0 0
   [junit4]   2> 4414201 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9093 (1605039713609056258)]} 0 0
   [junit4]   2> 4414202 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1428 (1605039713610104832)]} 0 0
   [junit4]   2> 4414202 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[5628 (-1605039713610104833)]} 0 0
   [junit4]   2> 4414202 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3449 (1605039713610104834)]} 0 0
   [junit4]   2> 4414202 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9746 (1605039713610104835)]} 0 0
   [junit4]   2> 4414243 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3718 (1605039713653096448)]} 0 3
   [junit4]   2> 4414244 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9543 (1605039713654145024)]} 0 0
   [junit4]   2> 4414244 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1642 (1605039713654145025)]} 0 0
   [junit4]   2> 4414245 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9218 (1605039713654145026)]} 0 0
   [junit4]   2> 4414245 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9756 (1605039713655193600)]} 0 0
   [junit4]   2> 4414245 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[379 (1605039713655193601)]} 0 0
   [junit4]   2> 4414246 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2102 (1605039713655193602)]} 0 0
   [junit4]   2> 4414246 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2932 (1605039713656242176)]} 0 0
   [junit4]   2> 4414444 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9e646dd[collection1] realtime]
   [junit4]   2> 4414446 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7491fd6c[collection1] realtime]
   [junit4]   2> 4414446 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:902 (-1605039713656242177)} 0 200
   [junit4]   2> 4414500 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5889 (1605039713922580480)]} 0 0
   [junit4]   2> 4414501 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6482 (1605039713923629056)]} 0 0
   [junit4]   2> 4414501 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4425 (1605039713923629057)]} 0 0
   [junit4]   2> 4414501 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1334 (1605039713923629058)]} 0 0
   [junit4]   2> 4414502 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7054 (1605039713924677632)]} 0 0
   [junit4]   2> 4414502 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7915 (1605039713924677633)]} 0 0
   [junit4]   2> 4414502 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5281 (1605039713924677634)]} 0 0
   [junit4]   2> 4414503 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1336 (1605039713925726208)]} 0 0
   [junit4]   2> 4414503 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3923 (1605039713925726209)]} 0 0
   [junit4]   2> 4414516 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6141 (1605039713938309120)]} 0 0
   [junit4]   2> 4414516 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2699 (1605039713939357696)]} 0 0
   [junit4]   2> 4414572 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2182 (1605039713939357697)]} 0 56
   [junit4]   2> 4414609 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4121 (1605039714036875264)]} 0 36
   [junit4]   2> 4414609 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4968 (1605039714036875265)]} 0 0
   [junit4]   2> 4414610 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6234 (1605039714037923840)]} 0 0
   [junit4]   2> 4414610 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5989 (1605039714037923841)]} 0 0
   [junit4]   2> 4414610 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6476 (1605039714037923842)]} 0 0
   [junit4]   2> 4414611 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6920 (1605039714038972416)]} 0 0
   [junit4]   2> 4414611 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9756 (1605039714038972417)]} 0 0
   [junit4]   2> 4414612 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[8063 (1605039714040020992)]} 0 0
   [junit4]   2> 4414612 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4645 (1605039714040020993)]} 0 0
   [junit4]   2> 4414612 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4994 (1605039714040020994)]} 0 0
   [junit4]   2> 4414612 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6525 (1605039714040020995)]} 0 0
   [junit4]   2> 4414612 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=307241018
   [junit4]   2> 4414629 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 4414629 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1fc5a9d6: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@15102fc2
   [junit4]   2> 4414646 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 4414646 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@dc36bd0: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@36eb059c
   [junit4]   2> 4414649 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 4414649 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@63b0bed1: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@39dee1e2
   [junit4]   2> 4414682 INFO  (coreCloseExecutor-6033-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@680a2359
   [junit4]   2> 4414682 INFO  (coreCloseExecutor-6033-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=680a2359
   [junit4]   2> 4414682 INFO  (coreCloseExecutor-6033-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3ea4f88d: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7f9e5b79
   [junit4]   2> 4414849 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4415049 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4415158 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4415727 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4416277 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 4416277 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 4416409 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4416461 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4416461 INFO  (TEST-TestRecovery.stressLogReplay-seed#[91DD4EE09180D192]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cada4f8
   [junit4]   2> 4416481 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4416597 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 4416636 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4417159 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4417177 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 4417178 INFO  (coreLoadExecutor-6043-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@4cada4f8
   [junit4]   2> 4417178 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 4417178 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_91DD4EE09180D192-001/init-core-data-001/]
   [junit4]   2> 4417200 WARN  (coreLoadExecutor-6043-thread-1) [    x:collection1] 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> 4417368 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 4417368 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 4417383 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4417383 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4417384 INFO  (coreLoadExecutor-6043-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=17, ma

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

.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_91DD4EE09180D192-001/init-core-data-001/]
   [junit4]   2> 4485632 WARN  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] 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> 4485681 INFO  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 4485681 INFO  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 4485685 INFO  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4485685 INFO  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4485686 INFO  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=1.7822265625, floorSegmentMB=1.40625, forceMergeDeletesPctAllowed=21.279967835114636, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8771380216212127, reclaimDeletesWeight=0.8025729024518511
   [junit4]   2> 4485690 INFO  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3981c8a4[collection1] main]
   [junit4]   2> 4485691 WARN  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 4485691 INFO  (coreLoadExecutor-6399-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4485696 INFO  (searcherExecutor-6400-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3981c8a4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_29(7.5.0):C3/2:delGen=1) Uninverting(_2a(7.5.0):C2:fieldInfosGen=1:dvGen=2) Uninverting(_2b(7.5.0):C3/1:delGen=1)))}
   [junit4]   2> 4485696 INFO  (TEST-TestRecovery.testLogReplay-seed#[91DD4EE09180D192]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 4485697 INFO  (TEST-TestRecovery.testLogReplay-seed#[91DD4EE09180D192]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=val_i_dvo:2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4485798 INFO  (TEST-TestRecovery.testLogReplay-seed#[91DD4EE09180D192]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testLogReplay
   [junit4]   2> 4485798 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4485798 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=509675026
   [junit4]   2> 4485798 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 4485798 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b9ef46a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@60800952
   [junit4]   2> 4485802 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 4485802 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2842b199: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@36eb059c
   [junit4]   2> 4485804 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 4485804 INFO  (SUITE-TestRecovery-seed#[91DD4EE09180D192]-worker) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@de17fe9: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@39dee1e2
   [junit4]   2> 4485804 INFO  (coreCloseExecutor-6405-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4a199798
   [junit4]   2> 4485804 INFO  (coreCloseExecutor-6405-thread-1-processing-x:collection1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4a199798
   [junit4]   2> 4485804 INFO  (coreCloseExecutor-6405-thread-1-processing-x:collection1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7a673bd4: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4034ec4e
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_91DD4EE09180D192-001
   [junit4]   2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=en-AU, timezone=CAT
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=4,threads=1,free=296364584,total=531628032
   [junit4]   2> NOTE: All tests run in this JVM: [ZkSolrClientTest, DistributedIntervalFacetingTest, TestAuthorizationFramework, SpatialHeatmapFacetsTest, TestFoldingMultitermQuery, TestDynamicURP, TestPolicyCloud, TestTrie, DistributedExpandComponentTest, TestCollationField, DeleteReplicaTest, TestDocumentBuilder, TestSolrCloudWithHadoopAuthPlugin, TestStreamBody, StressHdfsTest, SolrTestCaseJ4Test, RemoteQueryErrorTest, SpellCheckComponentTest, V2ApiIntegrationTest, TestSolr4Spatial2, TestScoreJoinQPScore, TestConfigsApi, TestNumericRangeQuery64, TestBulkSchemaConcurrent, TestCollectionsAPIViaSolrCloudCluster, TestSimpleTextCodec, TestHttpShardHandlerFactory, HdfsTlogReplayBufferedWhileIndexingTest, JavabinLoaderTest, OverseerStatusTest, TestHashPartitioner, BadIndexSchemaTest, TestDocTermOrdsUninvertLimit, ConnectionManagerTest, DocValuesTest, CollectionTooManyReplicasTest, TestConfigReload, TestXIncludeConfig, TermsComponentTest, TestWordDelimiterFilterFactory, TestSimpleTrackingShardHandler, SortSpecParsingTest, TestCryptoKeys, TestLMDirichletSimilarityFactory, TestMergePolicyConfig, TestMissingGroups, TestInitParams, DirectSolrConnectionTest, ResponseHeaderTest, SuggestComponentTest, VersionInfoTest, TestLeaderInitiatedRecoveryThread, LeaderFailoverAfterPartitionTest, TestCollapseQParserPlugin, SegmentsInfoRequestHandlerTest, TestCharFilters, TestQuerySenderNoQuery, InfixSuggestersTest, CopyFieldTest, SimpleFacetsTest, CoreMergeIndexesAdminHandlerTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, TestDistributedGrouping, TriggerSetPropertiesIntegrationTest, TestSerializedLuceneMatchVersion, IndexSchemaRuntimeFieldTest, AnalysisErrorHandlingTest, ExecutePlanActionTest, AddReplicaTest, AsyncCallRequestStatusResponseTest, ConcurrentDeleteAndCreateCollectionTest, CustomHighlightComponentTest, TestRTimerTree, TestSolrDeletionPolicy2, TestSolrConfigHandlerCloud, FileUtilsTest, TlogReplayBufferedWhileIndexingTest, PreAnalyzedFieldTest, MissingSegmentRecoveryTest, JvmMetricsTest, HighlighterMaxOffsetTest, PeerSyncTest, WrapperMergePolicyFactoryTest, TestManagedSynonymFilterFactory, TestLargeCluster, TestOmitPositions, TestExceedMaxTermLength, RegexBoostProcessorTest, XmlInterpolationTest, HdfsNNFailoverTest, SolrCmdDistributorTest, TestRecovery]
   [junit4] Completed [340/819 (1!)] on J2 in 82.10s, 20 tests, 1 failure <<< FAILURES!

[...truncated 368 lines...]
ERROR: command execution failed.
ERROR: Step ‘Archive the artifacts’ failed: no workspace for Lucene-Solr-BadApples-NightlyTests-7.x #16
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for Lucene-Solr-BadApples-NightlyTests-7.x #16
ERROR: lucene2 is offline; cannot locate JDK 1.8 (latest)
ERROR: lucene2 is offline; cannot locate JDK 1.8 (latest)
ERROR: lucene2 is offline; cannot locate JDK 1.8 (latest)
ERROR: lucene2 is offline; cannot locate JDK 1.8 (latest)
ERROR: lucene2 is offline; cannot locate JDK 1.8 (latest)
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
ERROR: lucene2 is offline; cannot locate JDK 1.8 (latest)
ERROR: lucene2 is offline; cannot locate JDK 1.8 (latest)

Mime
View raw message