[SOLR-16104] Test failure in TestCoreDiscovery.testTooManyTransientCores - ASF JIRA

XML

Word

Printable

JSON

Details

Type: Test
Status: Closed
Priority: Major
Resolution: Fixed
Affects Version/s: None
Fix Version/s: 9.2
Component/s: None
Labels:
None

Description

saw on jenkins:

https://jenkins.thetaphi.de/job/Solr-main-Linux/3527/testReport/junit/org.apache.solr.core/TestCoreDiscovery/testTooManyTransientCores/

Unfortunately this doesn't reproduce consistently in my local env, although I when beast testing, it seems to fail reasonably often.

gradlew -p solr/core beast -Ptests.dups=10 --tests TestCoreDiscovery.testTooManyTransientCores -Dtests.seed=EB078389206DB1B0 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=en-NF -Dtests.timezone=America/Kentucky/Monticello -Dtests.asserts=true -Dtests.file.encoding=UTF-8

  2> 100281 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.SolrTestCaseJ4 ###Starting testTooManyTransientCores
  2> 100629 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.c.SolrXmlConfig Loading solr.xml from /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/solr.xml
  2> 102013 WARN  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
  2> 102045 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.c.CorePropertiesLocator Found 7 core definitions underneath /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013
  2> 102045 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.c.CorePropertiesLocator Cores are: [coreT3, coreT2, coreT4, coreT1, coreT6, coreT5, coreLOS]
  2> 102045 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2 cores with initial capacity of 2
  2> 102049 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
  2> 102049 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
  2> 102049 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
  2> 102050 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.s.IndexSchema Schema name=tiny
  2> 102050 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.s.IndexSchema Schema name=tiny
  2> 102050 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.s.IndexSchema Schema name=tiny
  2> 102051 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
  2> 102051 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.c.CoreContainer Creating SolrCore 'coreT3' using configuration from instancedir /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT3, trusted=true
  2> 102051 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
  2> 102051 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.c.CoreContainer Creating SolrCore 'coreT2' using configuration from instancedir /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT2, trusted=true
  2> 102051 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.c.SolrCore Opening new SolrCore at [/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT3], dataDir=[/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT3/coreT3/]
  2> 102051 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
  2> 102051 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.c.CoreContainer Creating SolrCore 'coreT4' using configuration from instancedir /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT4, trusted=true
  2> 102051 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.c.SolrCore Opening new SolrCore at [/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT2], dataDir=[/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT2/coreT2/]
  2> 102052 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.c.SolrCore Opening new SolrCore at [/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT4], dataDir=[/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT4/coreT4/]
  2> 102053 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergedSegmentMB=33.533203125, floorSegmentMB=1.5068359375, forceMergeDeletesPctAllowed=26.155102560540648, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=27.752720215789576
  2> 102053 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergedSegmentMB=33.533203125, floorSegmentMB=1.5068359375, forceMergeDeletesPctAllowed=26.155102560540648, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=27.752720215789576
  2> 102053 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergedSegmentMB=33.533203125, floorSegmentMB=1.5068359375, forceMergeDeletesPctAllowed=26.155102560540648, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=27.752720215789576
  2> 102098 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
  2> 102098 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
  2> 102098 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
  2> 102098 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
  2> 102098 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
  2> 102098 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
  2> 102100 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergedSegmentMB=27.0927734375, floorSegmentMB=1.6640625, forceMergeDeletesPctAllowed=18.235234184975646, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=49.96764500514877
  2> 102100 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergedSegmentMB=27.0927734375, floorSegmentMB=1.6640625, forceMergeDeletesPctAllowed=18.235234184975646, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=49.96764500514877
  2> 102100 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergedSegmentMB=27.0927734375, floorSegmentMB=1.6640625, forceMergeDeletesPctAllowed=18.235234184975646, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=49.96764500514877
  2> 102101 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT3/conf
  2> 102101 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT2/conf
  2> 102101 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT4/conf
  2> 102102 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
  2> 102102 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
  2> 102102 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
  2> 102103 INFO  (searcherExecutor-883-thread-1-processing-coreT3) [ x:coreT3] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
  2> 102103 INFO  (searcherExecutor-885-thread-1-processing-coreT2) [ x:coreT2] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
  2> 102104 INFO  (searcherExecutor-887-thread-1-processing-coreT4) [ x:coreT4] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
  2> 102104 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT2] o.a.s.c.TransientSolrCoreCacheDefault Closing transient core [coreT4] evicted from the cache
  2> 102104 INFO  (CloserThread) [ x:coreT4] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@29d6807f coreT4
  2> 102105 INFO  (CloserThread) [ x:coreT4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coreT4 tag=SolrCore@29d6807f
  2> 102109 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
  2> 102109 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
  2> 102109 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
  2> 102111 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.s.IndexSchema Schema name=tiny
  2> 102111 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.s.IndexSchema Schema name=tiny
  2> 102111 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.s.IndexSchema Schema name=tiny
  2> 102112 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
  2> 102112 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.c.CoreContainer Creating SolrCore 'coreT5' using configuration from instancedir /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT5, trusted=true
  2> 102112 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
  2> 102112 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.c.CoreContainer Creating SolrCore 'coreT1' using configuration from instancedir /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT1, trusted=true
  2> 102112 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
  2> 102112 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.c.CoreContainer Creating SolrCore 'coreT6' using configuration from instancedir /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT6, trusted=true
  2> 102117 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.c.SolrCore Opening new SolrCore at [/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT5], dataDir=[/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT5/coreT5/]
  2> 102117 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.c.SolrCore Opening new SolrCore at [/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT1], dataDir=[/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT1/coreT1/]
  2> 102117 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.c.SolrCore Opening new SolrCore at [/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT6], dataDir=[/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT6/coreT6/]
  2> 102118 INFO  (CloserThread) [ x:coreT4] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
  2> 102118 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7551517048362257]
  2> 102118 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7551517048362257]
  2> 102118 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7551517048362257]
  2> 102160 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.u.CommitTracker Hard AutoCommit: disabled
  2> 102160 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.u.CommitTracker Soft AutoCommit: disabled
  2> 102160 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
  2> 102160 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
  2> 102160 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
  2> 102160 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
  2> 102160 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3966251070956206]
  2> 102160 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3966251070956206]
  2> 102161 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3966251070956206]
  2> 102161 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT5/conf
  2> 102161 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT6/conf
  2> 102161 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreT1/conf
  2> 102162 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
  2> 102162 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
  2> 102162 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
  2> 102162 INFO  (coreLoadExecutor-881-thread-1) [ x:coreT6] o.a.s.c.TransientSolrCoreCacheDefault Closing transient core [coreT2] evicted from the cache
  2> 102162 INFO  (CloserThread) [ x:coreT2] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@4b8ebb53 coreT2
  2> 102162 INFO  (coreLoadExecutor-881-thread-3) [ x:coreT1] o.a.s.c.TransientSolrCoreCacheDefault Closing transient core [coreT6] evicted from the cache
  2> 102162 INFO  (CloserThread) [ x:coreT2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coreT2 tag=SolrCore@4b8ebb53
  2> 102162 INFO  (coreLoadExecutor-881-thread-2) [ x:coreT5] o.a.s.c.TransientSolrCoreCacheDefault NOT evicting transient core [coreT1]; it's loading or something else.  Size: 2
  2> 102162 INFO  (coreLoadExecutor-881-thread-1) [] o.a.s.c.TransientSolrCoreCacheDefault NOT evicting transient core [coreT5]; it's loading or something else.  Size: 2
  2> 102163 INFO  (searcherExecutor-902-thread-1-processing-coreT6) [ x:coreT6] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
  2> 102163 INFO  (searcherExecutor-900-thread-1-processing-coreT1) [ x:coreT1] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
  2> 102163 INFO  (searcherExecutor-898-thread-1-processing-coreT5) [ x:coreT5] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
  2> 102168 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
  2> 102170 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.s.IndexSchema Schema name=tiny
  2> 102171 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.s.IndexSchema Loaded schema tiny/1.1 with uniqueid field id
  2> 102171 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.c.CoreContainer Creating SolrCore 'coreLOS' using configuration from instancedir /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreLOS, trusted=true
  2> 102174 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.c.SolrCore Opening new SolrCore at [/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreLOS], dataDir=[/Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreLOS/coreLOS/]
  2> 102175 INFO  (CloserThread) [ x:coreT2] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
  2> 102175 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergedSegmentMB=22.7197265625, floorSegmentMB=1.0654296875, forceMergeDeletesPctAllowed=2.8555280510223535, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=40.58237615430206
  2> 102175 INFO  (CloserThread) [ x:coreT6] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@28a91454 coreT6
  2> 102175 INFO  (CloserThread) [ x:coreT6] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coreT6 tag=SolrCore@28a91454
  2> 102190 INFO  (CloserThread) [ x:coreT6] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
  2> 102212 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.u.CommitTracker Hard AutoCommit: disabled
  2> 102212 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.u.CommitTracker Soft AutoCommit: disabled
  2> 102212 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergedSegmentMB=82.8056640625, floorSegmentMB=0.4765625, forceMergeDeletesPctAllowed=29.66554643767824, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7990975699935374, deletesPctAllowed=27.265857777238473
  2> 102213 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/mdrob/code/solr/solr/core/build/tmp/tests-tmp/solr.core.TestCoreDiscovery_EB078389206DB1B0-001/tempDir-013/coreLOS/conf
  2> 102213 INFO  (coreLoadExecutor-881-thread-1) [ x:coreLOS] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
  2> 102214 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1447770155
  2> 102214 INFO  (searcherExecutor-913-thread-1-processing-coreLOS) [ x:coreLOS] o.a.s.c.SolrCore Registered new searcher autowarm time: 0 ms
  2> 102214 INFO  (coreCloseExecutor-918-thread-1) [ x:coreLOS] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@6651b219 coreLOS
  2> 102214 INFO  (coreCloseExecutor-918-thread-1) [ x:coreLOS] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coreLOS tag=SolrCore@6651b219
  2> 102214 INFO  (coreCloseExecutor-918-thread-2) [ x:coreT5] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@5adfd468 coreT5
  2> 102214 INFO  (coreCloseExecutor-918-thread-3) [ x:coreT1] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@5c72ad64 coreT1
  2> 102215 INFO  (coreCloseExecutor-918-thread-4) [ x:coreT3] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@4288ddf9 coreT3
  2> 102226 INFO  (coreCloseExecutor-918-thread-2) [ x:coreT5] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coreT5 tag=SolrCore@5adfd468
  2> 102226 INFO  (coreCloseExecutor-918-thread-1) [ x:coreLOS] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
  2> 102240 INFO  (coreCloseExecutor-918-thread-3) [ x:coreT1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coreT1 tag=SolrCore@5c72ad64
  2> 102241 INFO  (coreCloseExecutor-918-thread-2) [ x:coreT5] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
  2> 102252 INFO  (coreCloseExecutor-918-thread-4) [ x:coreT3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.coreT3 tag=SolrCore@4288ddf9
  2> 102253 INFO  (coreCloseExecutor-918-thread-3) [ x:coreT1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
  2> 102264 INFO  (coreCloseExecutor-918-thread-4) [ x:coreT3] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
  2> 102265 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
  2> 102271 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
  2> 102276 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
  2> 102278 INFO  (TEST-TestCoreDiscovery.testTooManyTransientCores-seed#[EB078389206DB1B0]) [] o.a.s.SolrTestCaseJ4 ###Ending testTooManyTransientCores
   >     java.lang.AssertionError: There should only be 3 cores loaded, coreLOS and two coreT? cores expected:<3> but was:<4>
   >         at __randomizedtesting.SeedInfo.seed([EB078389206DB1B0:C1D3C32D6D6BF9AC]:0)
   >         at org.junit.Assert.fail(Assert.java:89)
   >         at org.junit.Assert.failNotEquals(Assert.java:835)
   >         at org.junit.Assert.assertEquals(Assert.java:647)
   >         at org.apache.solr.core.TestCoreDiscovery.testTooManyTransientCores(TestCoreDiscovery.java:330)
   >         at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   >         at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
   >         at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   >         at java.base/java.lang.reflect.Method.invoke(Method.java:568)
   >         at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1758)
   >         at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:946)
   >         at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:982)
   >         at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:996)
   >         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   >         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
   >         at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:44)
   >         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
   >         at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:45)
   >         at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
   >         at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
   >         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
   >         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   >         at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:375)
   >         at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:824)
   >         at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:475)
   >         at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:955)
   >         at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:840)
   >         at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:891)
   >         at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:902)
   >         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   >         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
   >         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
   >         at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   >         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
   >         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
   >         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   >         at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
   >         at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   >         at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   >         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   >         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   >         at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   >         at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:43)
   >         at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:44)
   >         at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:60)
   >         at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:47)
   >         at org.junit.rules.RunRules.evaluate(RunRules.java:20)
   >         at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   >         at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:375)
   >         at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:831)
   >         at java.base/java.lang.Thread.run(Thread.java:833)
  2> NOTE: reproduce with: gradlew test --tests TestCoreDiscovery.testTooManyTransientCores -Dtests.seed=EB078389206DB1B0 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=en-NF -Dtests.timezone=America/Kentucky/Monticello -Dtests.asserts=true -Dtests.file.encoding=UTF-8

Attachments

Issue Links

is caused by

SOLR-15964 Transient cores should not be unloaded/evicted while it's being used

Closed

links to

GitHub Pull Request #772

GitHub Pull Request #1355

Activity

People

Assignee:: David Smiley

Reporter:: Mike Drob

Votes:: 0 Vote for this issue

Watchers:: 7 Start watching this issue

Dates

Created:: 16/Mar/22 20:07

Updated:: 27/Mar/23 18:04

Resolved:: 01/Mar/23 21:29

Time Tracking

Estimated:

Not Specified

Remaining:

Logged:

1h 20m