Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/3435/
Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.search.TestRecovery
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([EA3C7D949B593E50]:0)
FAILED: org.apache.solr.search.TestRecovery.testExistOldBufferLog
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([EA3C7D949B593E50]:0)
Build Log:
[...truncated 15287 lines...]
[junit4] Suite: org.apache.solr.search.TestRecovery
[junit4] 2> 112821 INFO
(SUITE-TestRecovery-seed#[EA3C7D949B593E50]-worker) [ ] o.a.s.SolrTestCaseJ4
SecureRandom sanity checks: test.solr.allowed.securerandom=null &
java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001
[junit4] 2> 112822 WARN
(SUITE-TestRecovery-seed#[EA3C7D949B593E50]-worker) [ ] o.a.s.SolrTestCaseJ4
startTrackingSearchers: numOpens=30 numCloses=30
[junit4] 2> 112822 INFO
(SUITE-TestRecovery-seed#[EA3C7D949B593E50]-worker) [ ] o.a.s.SolrTestCaseJ4
Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 112823 INFO
(SUITE-TestRecovery-seed#[EA3C7D949B593E50]-worker) [ ] o.a.s.SolrTestCaseJ4
Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 112825 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testLogReplayWithReorderedDBQByIdAndChildDocs
[junit4] 2> 112825 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
[junit4] 2> 112825 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 112825 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from
paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 112876 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 112903 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 112906 WARN
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis
class [solr.TrieFloatField]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 112908 WARN
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis
class [solr.TrieDoubleField]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 112914 WARN
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis
class [solr.TrieDateField]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 112989 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 113117 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 113156 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 113156 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 113176 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 113184 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 113185 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 113210 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 113251 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 113289 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 113362 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 113372 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 113372 INFO (coreLoadExecutor-394-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@57f81849
[junit4] 2> 113372 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/]
[junit4] 2> 113376 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=26, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=54.24609375,
floorSegmentMB=2.0400390625, forceMergeDeletesPctAllowed=15.730445568203946,
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.834133854505613, deletesPctAllowed=35.60553318604755
[junit4] 2> 113556 WARN (coreLoadExecutor-394-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> 113596 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 113596 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 113598 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 113598 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 113599 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 113601 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@321ceb5e[collection1] main]
[junit4] 2> 113601 WARN (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 113601 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 113602 INFO (coreLoadExecutor-394-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1623296266560602112
[junit4] 2> 113604 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 113604 INFO
(searcherExecutor-395-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@321ceb5e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 113609 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM
INDEX
[junit4] 2> 113610 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e1b1ee3[collection1]
realtime]
[junit4] 2> 113610 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] 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 5
[junit4] 2> 113610 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1623296266568990720,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 113610 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@e6f00fa
commitCommandVersion:1623296266568990720
[junit4] 2> 113626 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ec5f845[collection1] main]
[junit4] 2> 113627 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 113628 INFO (searcherExecutor-395-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4ec5f845[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 113629 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{commit=} 0 18
[junit4] 2> 113635 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ3_1]}
0 4
[junit4] 2> 113637 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ3_2
(3)]} 0 1
[junit4] 2> 113645 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3805c793[collection1]
realtime]
[junit4] 2> 113659 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@36aec193[collection1]
realtime]
[junit4] 2> 113660 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null
params={update.distrib=FROMLEADER&_version_=-2&wt=json&indent=true}{deleteByQuery=id:RDBQ3_1
id:RDBQ3_2 id:RDBQ3_3 id:RDBQ3_4 (-2)} 0 22
[junit4] 2> 113660 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.
Update=add{_version_=1,id=RDBQ3_3} DBQs=[DBQ{version=2,q=id:RDBQ3_1 id:RDBQ3_2
id:RDBQ3_3 id:RDBQ3_4}]
[junit4] 2> 113679 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@45ad8558[collection1]
realtime]
[junit4] 2> 113679 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ3_3
(1)]} 0 19
[junit4] 2> 113681 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ3_4
(4)]} 0 0
[junit4] 2> 113685 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=*:*&wt=xml} hits=0 status=0 QTime=3
[junit4] 2> 113686 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=889093050
[junit4] 2> 113687 INFO (coreCloseExecutor-400-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@e530b99
[junit4] 2> 113687 INFO (coreCloseExecutor-400-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=e530b99
[junit4] 2> 113687 INFO (coreCloseExecutor-400-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3beb6668: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@aa8d139
[junit4] 2> 113721 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 113721 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5121d1a8: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@4d729e00
[junit4] 2> 113727 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 113727 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@20e3f76d: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@2462ba15
[junit4] 2> 113730 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 113730 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@65a67ec: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@350340fb
[junit4] 2> 113741 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from
paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 113756 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 113767 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 113864 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 114044 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 114109 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 114109 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 114132 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 114173 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 114173 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 114206 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 114248 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 114266 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 114362 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 114397 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 114397 INFO (coreLoadExecutor-410-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@57f81849
[junit4] 2> 114397 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/]
[junit4] 2> 114418 WARN (coreLoadExecutor-410-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> 114514 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 114514 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 114522 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 114522 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 114530 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=26, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=54.24609375,
floorSegmentMB=2.0400390625, forceMergeDeletesPctAllowed=15.730445568203946,
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.834133854505613, deletesPctAllowed=35.60553318604755
[junit4] 2> 114538 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@300f80ba[collection1] main]
[junit4] 2> 114539 WARN (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 114539 INFO (coreLoadExecutor-410-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 114546 INFO
(searcherExecutor-411-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@300f80ba[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 114565 WARN
(recoveryExecutor-413-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.UpdateLog Starting log replay
tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/tlog/tlog.0000000000000000001.0
refcount=1} active=false starting pos=0 inSortedOrder=false
[junit4] 2> 114567 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 114833 INFO
(recoveryExecutor-413-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@9162060[collection1] realtime]
[junit4] 2> 114835 INFO
(recoveryExecutor-413-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@78a42548[collection1] realtime]
[junit4] 2> 114836 INFO
(replayUpdatesExecutor-404-thread-1-processing-x:collection1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.
Update=add{flags=a,_version_=1,id=RDBQ3_3} DBQs=[DBQ{version=2,q=id:RDBQ3_1
id:RDBQ3_2 id:RDBQ3_3 id:RDBQ3_4}]
[junit4] 2> 114842 INFO
(replayUpdatesExecutor-404-thread-1-processing-x:collection1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@63e6b546[collection1] realtime]
[junit4] 2> 115086 INFO
(recoveryExecutor-413-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> 115086 INFO
(recoveryExecutor-413-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@7a3ca9cf commitCommandVersion:0
[junit4] 2> 115404 INFO
(recoveryExecutor-413-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@380398bf[collection1] main]
[junit4] 2> 115405 INFO
(recoveryExecutor-413-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 115411 INFO
(searcherExecutor-411-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@380398bf[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):C6/1:[diagnostics={os=Linux,
java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11,
lucene.version=7.7.0, os.arch=amd64, java.runtime.version=9.0.4+11,
source=flush, os.version=4.15.0-43-generic,
timestamp=1548095958565}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1)
Uninverting(_3(7.7.0):C3:[diagnostics={os=Linux, java.vendor=Oracle
Corporation, java.version=9.0.4, java.vm.version=9.0.4+11,
lucene.version=7.7.0, os.arch=amd64, java.runtime.version=9.0.4+11,
source=flush, os.version=4.15.0-43-generic,
timestamp=1548095958822}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 115411 INFO
(recoveryExecutor-413-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[RDBQ3_1, RDBQ3_2 (3),
RDBQ3_3 (1), RDBQ3_4 (4)],deleteByQuery=id:RDBQ3_1 id:RDBQ3_2 id:RDBQ3_3
id:RDBQ3_4 (-2)} 0 845
[junit4] 2> 115411 WARN
(recoveryExecutor-413-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=4
deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4] 2> 115412 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=*:*&wt=xml} hits=8 status=0 QTime=0
[junit4] 2> 115415 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 115415 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1509504933
[junit4] 2> 115437 INFO (coreCloseExecutor-416-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@83e5518
[junit4] 2> 115437 INFO (coreCloseExecutor-416-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=83e5518
[junit4] 2> 115437 INFO (coreCloseExecutor-416-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3003faa9: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@5221025d
[junit4] 2> 115461 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 115461 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@31a92ecd: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@41d8c243
[junit4] 2> 115466 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 115466 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@18c0dab0: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@2462ba15
[junit4] 2> 115469 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 115469 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6f293a0f: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@350340fb
[junit4] 2> 115489 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQByIdAndChildDocs-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testLogReplayWithReorderedDBQByIdAndChildDocs
[junit4] 2> 115492 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testBufferedMultipleCalls
[junit4] 2> 115492 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
[junit4] 2> 115492 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 115492 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 115508 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 115525 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 115589 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 115759 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 115812 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 115812 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history
in memory.
[junit4] 2> 115825 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 115832 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 115832 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 115834 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 115845 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 115858 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 115900 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 115908 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 115908 INFO (coreLoadExecutor-426-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@57f81849
[junit4] 2> 115908 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/]
[junit4] 2> 115911 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=26, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=54.24609375,
floorSegmentMB=2.0400390625, forceMergeDeletesPctAllowed=15.730445568203946,
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.834133854505613, deletesPctAllowed=35.60553318604755
[junit4] 2> 116009 WARN (coreLoadExecutor-426-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> 116042 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 116042 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 116044 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 116044 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 116045 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 116045 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@53ba3675[collection1] main]
[junit4] 2> 116046 WARN (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 116046 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 116046 INFO (coreLoadExecutor-426-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1623296269123321856
[junit4] 2> 116048 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 116048 INFO
(searcherExecutor-427-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@53ba3675[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 116049 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 116049 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@51c0c8c6[collection1] realtime]
[junit4] 2> 116049 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
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> 116050 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1623296269127516160,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 116050 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@538886ab
commitCommandVersion:1623296269127516160
[junit4] 2> 116066 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1ebabc18[collection1] main]
[junit4] 2> 116066 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 116067 INFO (searcherExecutor-427-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1ebabc18[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 116067 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 17
[junit4] 2> 116068 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 116069 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c1 (5)]} 0 0
[junit4] 2> 116069 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c2 (6)]} 0 0
[junit4] 2> 116069 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c3 (7)]} 0 0
[junit4] 2> 116069 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=BUFFERING,
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/tlog/tlog.0000000000000000001.5
refcount=1}}
[junit4] 2> 116070 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c4 (8)]} 0 0
[junit4] 2> 116070 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c5 (9)]} 0 0
[junit4] 2> 116071 WARN (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.UpdateLog Starting log replay
tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/tlog/buffer.tlog.0000262609152612376
refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 116321 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 116322 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@538886ab commitCommandVersion:0
[junit4] 2> 116392 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@249d45ff[collection1] main]
[junit4] 2> 116393 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 116394 INFO (searcherExecutor-427-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@249d45ff[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):C2:[diagnostics={os=Linux,
java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11,
lucene.version=7.7.0, os.arch=amd64, java.runtime.version=9.0.4+11,
source=flush, os.version=4.15.0-43-generic,
timestamp=1548095960057}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 116394 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[c4 (8), c5 (9)]} 0 323
[junit4] 2> 116394 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 116396 WARN (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2
deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 116396 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.c.S.Request [collection1] webapp=null path=null
params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
[junit4] 2> 116399 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c100 (10)]} 0 1
[junit4] 2> 116399 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c101 (11)]} 0 0
[junit4] 2> 116399 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/tlog/tlog.0000000000000000002.10
refcount=1}}
[junit4] 2> 116400 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c103 (12)]} 0 0
[junit4] 2> 116400 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c104 (13)]} 0 0
[junit4] 2> 116400 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=BUFFERING,
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/tlog/tlog.0000000000000000002.10
refcount=1}}
[junit4] 2> 116400 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c105 (14)]} 0 0
[junit4] 2> 116400 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[c106 (15)]} 0 0
[junit4] 2> 116400 WARN (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.UpdateLog Starting log replay
tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/tlog/buffer.tlog.0000262609483065110
refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 116651 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 116651 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@538886ab commitCommandVersion:0
[junit4] 2> 116732 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@444227b1[collection1] main]
[junit4] 2> 116733 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 116734 INFO (searcherExecutor-427-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@444227b1[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):C2:[diagnostics={os=Linux,
java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11,
lucene.version=7.7.0, os.arch=amd64, java.runtime.version=9.0.4+11,
source=flush, os.version=4.15.0-43-generic,
timestamp=1548095960057}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_1(7.7.0):C4:[diagnostics={os=Linux, java.vendor=Oracle
Corporation, java.version=9.0.4, java.vm.version=9.0.4+11,
lucene.version=7.7.0, os.arch=amd64, java.runtime.version=9.0.4+11,
source=flush, os.version=4.15.0-43-generic,
timestamp=1548095960388}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 116734 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[c105 (14), c106 (15)]}
0 334
[junit4] 2> 116735 INFO (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 116736 WARN (recoveryExecutor-429-thread-1) [ ]
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2
deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 116746 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=*:*&fl=id,_version_&sort=_version_+asc&wt=xml} hits=6 status=0 QTime=9
[junit4] 2> 116748 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.c.S.Request [collection1] webapp=null path=null
params={qt=/get&getVersions=6&wt=xml} status=0 QTime=0
[junit4] 2> 116749 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 116749 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2062459339
[junit4] 2> 116754 INFO (coreCloseExecutor-432-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@7f38a874
[junit4] 2> 116754 INFO (coreCloseExecutor-432-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=7f38a874
[junit4] 2> 116754 INFO (coreCloseExecutor-432-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2eee18b3: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2e105b3e
[junit4] 2> 116789 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 116789 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@42bb7352: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@5c44c692
[junit4] 2> 116793 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 116794 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@d2fbca0: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@2462ba15
[junit4] 2> 116797 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 116797 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6b613443: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@350340fb
[junit4] 2> 116801 INFO
(TEST-TestRecovery.testBufferedMultipleCalls-seed#[EA3C7D949B593E50]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testBufferedMultipleCalls
[junit4] 2> 116805 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.SolrTestCaseJ4 ###Starting testLogReplayWithReorderedDBQ
[junit4] 2> 116805 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
[junit4] 2> 116805 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 116805 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 116823 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 116833 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 116893 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 117013 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 117028 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 117028 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 117041 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 117048 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 117048 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 117050 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 117067 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 117083 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 117144 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 117152 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 117152 INFO (coreLoadExecutor-442-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@57f81849
[junit4] 2> 117153 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/]
[junit4] 2> 117155 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=26, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=54.24609375,
floorSegmentMB=2.0400390625, forceMergeDeletesPctAllowed=15.730445568203946,
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.834133854505613, deletesPctAllowed=35.60553318604755
[junit4] 2> 117175 WARN (coreLoadExecutor-442-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> 117221 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 117221 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 117223 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 117223 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 117224 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 117225 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@38033a3c[collection1] main]
[junit4] 2> 117225 WARN (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 117226 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 117226 INFO (coreLoadExecutor-442-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1623296270360641536
[junit4] 2> 117228 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 117229 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 117230 INFO
(searcherExecutor-443-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@38033a3c[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 117231 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f8fd4c3[collection1] realtime]
[junit4] 2> 117231 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] 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 2
[junit4] 2> 117231 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1623296270365884416,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 117231 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@6d675217
commitCommandVersion:1623296270365884416
[junit4] 2> 117249 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.s.SolrIndexSearcher Opening [Searcher@19d5637a[collection1] main]
[junit4] 2> 117250 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 117251 INFO (searcherExecutor-443-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@19d5637a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 117252 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 20
[junit4] 2> 117253 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ1_1 (16)]} 0 1
[junit4] 2> 117259 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.s.SolrIndexSearcher Opening [Searcher@60137a43[collection1] realtime]
[junit4] 2> 117260 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&_version_=-18&wt=json&indent=true}{deleteByQuery=id:RDBQ1_2
(-18)} 0 6
[junit4] 2> 117260 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.
Update=add{_version_=17,id=RDBQ1_2} DBQs=[DBQ{version=18,q=id:RDBQ1_2}]
[junit4] 2> 117264 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.s.SolrIndexSearcher Opening [Searcher@36582415[collection1] realtime]
[junit4] 2> 117265 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ1_2 (17)]} 0 4
[junit4] 2> 117265 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ1_3 (19)]} 0 0
[junit4] 2> 117266 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml}
hits=0 status=0 QTime=0
[junit4] 2> 117266 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1844087748
[junit4] 2> 117266 INFO (coreCloseExecutor-448-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@15aba272
[junit4] 2> 117266 INFO (coreCloseExecutor-448-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=15aba272
[junit4] 2> 117266 INFO (coreCloseExecutor-448-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@56eea7df: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@11ba8fce
[junit4] 2> 117275 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 117275 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@39d9b1df: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@5b48405e
[junit4] 2> 117278 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 117279 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6639da62: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@2462ba15
[junit4] 2> 117281 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 117281 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@12ec7344: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@350340fb
[junit4] 2> 117282 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 117295 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 117304 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 117373 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 117430 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 117441 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 117441 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 117451 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 117455 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 117455 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 117457 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 117471 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 117477 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 117518 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 117525 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 117525 INFO (coreLoadExecutor-458-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@57f81849
[junit4] 2> 117525 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/]
[junit4] 2> 117529 WARN (coreLoadExecutor-458-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> 117561 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 117562 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 117564 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 117564 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 117566 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=26, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=54.24609375,
floorSegmentMB=2.0400390625, forceMergeDeletesPctAllowed=15.730445568203946,
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.834133854505613, deletesPctAllowed=35.60553318604755
[junit4] 2> 117567 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@3ba61564[collection1] main]
[junit4] 2> 117567 WARN (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 117568 INFO (coreLoadExecutor-458-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 117570 WARN
(recoveryExecutor-461-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.UpdateLog Starting log replay
tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/tlog/tlog.0000000000000000001
refcount=2} active=false starting pos=0 inSortedOrder=false
[junit4] 2> 117570 INFO
(searcherExecutor-459-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3ba61564[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 117571 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml}
hits=0 status=0 QTime=0
[junit4] 2> 117827 INFO
(recoveryExecutor-461-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3dc6104f[collection1] realtime]
[junit4] 2> 117828 INFO
(replayUpdatesExecutor-452-thread-1-processing-x:collection1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.
Update=add{flags=a,_version_=17,id=RDBQ1_2} DBQs=[DBQ{version=18,q=id:RDBQ1_2}]
[junit4] 2> 117832 INFO
(replayUpdatesExecutor-452-thread-1-processing-x:collection1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4764df[collection1]
realtime]
[junit4] 2> 118078 INFO
(recoveryExecutor-461-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> 118078 INFO
(recoveryExecutor-461-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@62961520 commitCommandVersion:0
[junit4] 2> 118189 INFO
(recoveryExecutor-461-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@432df7e0[collection1] main]
[junit4] 2> 118190 INFO
(recoveryExecutor-461-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 118191 INFO
(searcherExecutor-459-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@432df7e0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.7.0):C1:[diagnostics={os=Linux,
java.vendor=Oracle Corporation, java.version=9.0.4, java.vm.version=9.0.4+11,
lucene.version=7.7.0, os.arch=amd64, java.runtime.version=9.0.4+11,
source=flush, os.version=4.15.0-43-generic,
timestamp=1548095961558}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
Uninverting(_3(7.7.0):C1:[diagnostics={os=Linux, java.vendor=Oracle
Corporation, java.version=9.0.4, java.vm.version=9.0.4+11,
lucene.version=7.7.0, os.arch=amd64, java.runtime.version=9.0.4+11,
source=flush, os.version=4.15.0-43-generic,
timestamp=1548095961814}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 118191 INFO
(recoveryExecutor-461-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[RDBQ1_1 (16), RDBQ1_2
(17), RDBQ1_3 (19)],deleteByQuery=id:RDBQ1_2 (-18)} 0 621
[junit4] 2> 118191 WARN
(recoveryExecutor-461-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=3
deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
[junit4] 2> 118191 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml}
hits=2 status=0 QTime=0
[junit4] 2> 118192 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 118192 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1150977046
[junit4] 2> 118193 INFO (coreCloseExecutor-464-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@6cd61943
[junit4] 2> 118193 INFO (coreCloseExecutor-464-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=6cd61943
[junit4] 2> 118193 INFO (coreCloseExecutor-464-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3a33f017: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6cbc7d4b
[junit4] 2> 118204 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 118204 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@269f027c: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@136e7ec9
[junit4] 2> 118209 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 118209 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5ff32211: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@2462ba15
[junit4] 2> 118212 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 118212 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@71b88a89: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@350340fb
[junit4] 2> 118216 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQ-seed#[EA3C7D949B593E50]) [
] o.a.s.SolrTestCaseJ4 ###Ending testLogReplayWithReorderedDBQ
[junit4] 2> 118219 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testLogReplayWithReorderedDBQUpdateWithDifferentChildCount
[junit4] 2> 118219 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
[junit4] 2> 118220 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 118220 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from
paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 118236 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 118245 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 118314 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 118362 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 118374 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 118374 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 118383 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 118388 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 118388 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@57f81849
[junit4] 2> 118390 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 118406 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0
[junit4] 2> 118414 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 118471 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 118479 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 118480 INFO (coreLoadExecutor-474-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@57f81849
[junit4] 2> 118480 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_EA3C7D949B593E50-001/init-core-data-001/]
[junit4] 2> 118483 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=26, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=54.24609375,
floorSegmentMB=2.0400390625, forceMergeDeletesPctAllowed=15.730445568203946,
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.834133854505613, deletesPctAllowed=35.60553318604755
[junit4] 2> 118502 WARN (coreLoadExecutor-474-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> 118531 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 118532 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 118533 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 118533 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 118534 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 118534 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@5ad2cf94[collection1] main]
[junit4] 2> 118534 WARN (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 118535 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 118535 INFO (coreLoadExecutor-474-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1623296271733227520
[junit4] 2> 118537 INFO
(searcherExecutor-475-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5ad2cf94[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 118537 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 118538 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM
INDEX
[junit4] 2> 118539 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@316bd26e[collection1]
realtime]
[junit4] 2> 118539 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] 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> 118539 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1623296271737421824,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 118539 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@69ba96af
commitCommandVersion:1623296271737421824
[junit4] 2> 118557 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@505c4736[collection1] main]
[junit4] 2> 118557 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 118559 INFO (searcherExecutor-475-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@505c4736[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 118559 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={}{commit=} 0 19
[junit4] 2> 118561 INFO
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[EA3C7D949B593E50])
[ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null
path=null params={update.distrib=FROMLEADER&wt=json&indent=true
[...truncated too long message...]
[junit4] 2> at
app//org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at
java.base@9.0.4/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 4) Thread[id=2096, name=SolrRrdBackendFactory-828-thread-2,
state=TIMED_WAITING, group=TGRP-TestRecovery]
[junit4] 2> at
java.base@9.0.4/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2104)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at
java.base@9.0.4/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 5) Thread[id=2089, name=MetricsHistoryHandler-829-thread-1,
state=TIMED_WAITING, group=TGRP-TestRecovery]
[junit4] 2> at
java.base@9.0.4/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2104)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1131)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:848)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at
java.base@9.0.4/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 6) Thread[id=2093, name=searcherExecutor-831-thread-1,
state=WAITING, group=TGRP-TestRecovery]
[junit4] 2> at
java.base@9.0.4/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2062)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1092)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1152)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at
java.base@9.0.4/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 7) Thread[id=2095,
name=coreCloseExecutor-836-thread-1-processing-x:collection1, state=RUNNABLE,
group=TGRP-TestRecovery]
[junit4] 2> at
java.base@9.0.4/java.lang.Throwable.fillInStackTrace(Native Method)
[junit4] 2> at
java.base@9.0.4/java.lang.Throwable.fillInStackTrace(Throwable.java:787)
[junit4] 2> at
java.base@9.0.4/java.lang.Throwable.<init>(Throwable.java:255)
[junit4] 2> at
java.base@9.0.4/java.lang.Exception.<init>(Exception.java:54)
[junit4] 2> at
java.base@9.0.4/java.lang.InterruptedException.<init>(InterruptedException.java:57)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2090)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor.awaitTermination(ThreadPoolExecutor.java:1491)
[junit4] 2> at
app//org.apache.solr.common.util.ExecutorUtil.awaitTermination(ExecutorUtil.java:84)
[junit4] 2> at
app//org.apache.solr.common.util.ExecutorUtil.shutdownAndAwaitTermination(ExecutorUtil.java:76)
[junit4] 2> at
app//org.apache.solr.update.UpdateLog.close(UpdateLog.java:1380)
[junit4] 2> at
app//org.apache.solr.update.CdcrUpdateLog.close(CdcrUpdateLog.java:478)
[junit4] 2> at
app//org.apache.solr.update.UpdateLog.close(UpdateLog.java:1350)
[junit4] 2> at
app//org.apache.solr.update.DirectUpdateHandler2.closeWriter(DirectUpdateHandler2.java:840)
[junit4] 2> at
app//org.apache.solr.update.DefaultSolrCoreState.closeIndexWriter(DefaultSolrCoreState.java:105)
[junit4] 2> at
app//org.apache.solr.update.DefaultSolrCoreState.close(DefaultSolrCoreState.java:399)
[junit4] 2> at
app//org.apache.solr.update.SolrCoreState.decrefSolrCoreState(SolrCoreState.java:84)
[junit4] 2> at
app//org.apache.solr.core.SolrCore.close(SolrCore.java:1583)
[junit4] 2> at
app//org.apache.solr.core.SolrCores.lambda$close$0(SolrCores.java:141)
[junit4] 2> at
app//org.apache.solr.core.SolrCores$$Lambda$603/973396365.call(Unknown Source)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at
app//org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] 2> at
app//org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$127/1096239770.run(Unknown
Source)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base@9.0.4/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at
java.base@9.0.4/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=RandomSimilarity(queryNorm=false): {}, locale=ms-MY, timezone=America/Manaus
[junit4] 2> NOTE: Linux 4.15.0-43-generic amd64/Oracle Corporation 9.0.4
(64-bit)/cpus=8,threads=12,free=311239448,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [CdcrBootstrapTest,
TestCharFilters, TestSolrJ, HDFSCollectionsAPITest, DOMUtilTest,
TestClassicSimilarityFactory, RangeFacetCloudTest, JavabinLoaderTest,
TestRTimerTree, TestSimDistribStateManager, NodeAddedTriggerTest,
SecurityConfHandlerTest, TestConfigSetImmutable, LargeFieldTest,
TestCodecSupport, TestFieldCacheVsDocValues, TestRecovery]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRecovery
-Dtests.seed=EA3C7D949B593E50 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=ms-MY -Dtests.timezone=America/Manaus -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | TestRecovery (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([EA3C7D949B593E50]:0)
[junit4] Completed [715/837 (1!)] on J2 in 7205.62s, 13 tests, 2 errors <<<
FAILURES!
[...truncated 42752 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/3435/consoleText
[repro] Revision: e473c351dc5f23bb2241ccbc66322eae85082fa7
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestRecovery
-Dtests.method=testExistOldBufferLog -Dtests.seed=EA3C7D949B593E50
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ms-MY
-Dtests.timezone=America/Manaus -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=TestRecovery
-Dtests.seed=EA3C7D949B593E50 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=ms-MY -Dtests.timezone=America/Manaus -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestRecovery
[repro] ant compile-test
[...truncated 3588 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.TestRecovery" -Dtests.showOutput=onerror
"-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=EA3C7D949B593E50
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ms-MY
-Dtests.timezone=America/Manaus -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 170 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.search.TestRecovery
[repro] Exiting with code 0
[...truncated 40 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
