You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by "Hoss Man (JIRA)" <ji...@apache.org> on 2015/05/30 03:26:17 UTC
[jira] [Updated] (SOLR-7611) TestSearcherReuse failure
[ https://issues.apache.org/jira/browse/SOLR-7611?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Hoss Man updated SOLR-7611:
---------------------------
Attachment: SOLR-7611_test.patch
This test does a bunch of "commits" that are suppose to be No-Ops and then confirms the the searcher hasn't changed.
if you read the logs, you can see that just before the failures, there are in fact new segment files, so there are new readers, so the searchers must change -- but the attached patch makes this more clear by adding some additional asserts on the underlying readers before comparing the searchers themselves. (the toString of the searcher that gets printed in the existing assert shows it's Object.hashCode() to make it clear they are diff, and the reader toString()s are included, but the reader.toString()s can look identicale even though they are not -- hence the new asserts)
it's not yet clear to me why the new segment files are getting written _sometimes_ .. best guess is that maybe this is somehow related to LUCENE-6505?
> TestSearcherReuse failure
> -------------------------
>
> Key: SOLR-7611
> URL: https://issues.apache.org/jira/browse/SOLR-7611
> Project: Solr
> Issue Type: Bug
> Affects Versions: 5.2
> Reporter: Steve Rowe
> Attachments: SOLR-7611_test.patch
>
>
> {noformat}
> [junit4] FAILURE 0.94s | TestSearcherReuse.test <<<
> [junit4] > Throwable #1: java.lang.AssertionError: expected same:<Searcher@66681f2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3) Uninverting(_2(5.2.0):c2)))}> was not:<Searcher@5d94043f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3) Uninverting(_2(5.2.0):c2)))}>
> [junit4] > at __randomizedtesting.SeedInfo.seed([F1A11DF972B907D6:79F52223DC456A2E]:0)
> [junit4] > at org.apache.solr.search.TestSearcherReuse.assertSearcherHasNotChanged(TestSearcherReuse.java:247)
> [junit4] > at org.apache.solr.search.TestSearcherReuse.test(TestSearcherReuse.java:104)
> [junit4] > at java.lang.Thread.run(Thread.java:745)
> {noformat}
> Reproduces for me on the 5.2 release branch with the following - note that both {{-Dtests.multiplier=2}} and {{-Dtests.nightly=true}} are required to reproduce:
> {noformat}
> ant test -Dtestcase=TestSearcherReuse -Dtests.seed=F1A11DF972B907D6 -Dtests.multiplier=2 -Dtests.nightly=true
> {noformat}
> Full log:
> {noformat}
> [junit4] <JUnit4> says hallo! Master seed: F1A11DF972B907D6
> [junit4] Executing 1 suite with 1 JVM.
> [junit4]
> [junit4] Started J0 PID(776@smb.local).
> [junit4] Suite: org.apache.solr.search.TestSearcherReuse
> [junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout.
> [junit4] 2> Creating dataDir: /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001
> [junit4] 2> 889 T11 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
> [junit4] 2> 959 T11 oas.SolrTestCaseJ4.initCore ####initCore
> [junit4] 2> 1093 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/'
> [junit4] 2> 1390 T11 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
> [junit4] 2> 1449 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
> [junit4] 2> 1551 T11 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-managed-schema.xml
> [junit4] 2> 1563 T11 oass.ManagedIndexSchemaFactory.readSchemaLocally The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-id-and-version-fields-only.xml instead
> [junit4] 2> 1580 T11 oass.IndexSchema.readSchema Reading Solr Schema from /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/conf/schema-id-and-version-fields-only.xml
> [junit4] 2> 1594 T11 oass.IndexSchema.readSchema [null] Schema name=id-and-version-fields-only
> [junit4] 2> 1676 T11 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 1706 T11 oass.ManagedIndexSchema.persistManagedSchema Upgraded to managed schema at /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/conf/managed-schema
> [junit4] 2> 1709 T11 oass.ManagedIndexSchemaFactory.upgradeToManagedSchema After upgrading to managed schema, renamed the non-managed schema /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/conf/schema-id-and-version-fields-only.xml to /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/conf/schema-id-and-version-fields-only.xml.bak
> [junit4] 2> 1714 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
> [junit4] 2> 1715 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001
> [junit4] 2> 1715 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/'
> [junit4] 2> 1765 T11 oasc.CoreContainer.<init> New CoreContainer 731222945
> [junit4] 2> 1767 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/]
> [junit4] 2> 1768 T11 oasc.CoreContainer.load loading shared library: /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/lib
> [junit4] 2> 1769 T11 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/lib).
> [junit4] 2> 1821 T11 oashc.HttpShardHandlerFactory.init created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
> [junit4] 2> 2086 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
> [junit4] 2> 2093 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
> [junit4] 2> 2096 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
> [junit4] 2> 2099 T11 oasc.CoreContainer.load Node Name: testNode
> [junit4] 2> 2100 T11 oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used.
> [junit4] 2> 2265 T11 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {dataDir=/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001, config=solrconfig-managed-schema.xml, shard=shard1, collection=collection1, name=collection1, instanceDir=collection1, loadOnStartup=true, schema=managed-schema, absoluteInstDir=/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/, transient=false}
> [junit4] 2> 2269 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/'
> [junit4] 2> 2309 T12 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
> [junit4] 2> 2335 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
> [junit4] 2> 2372 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-managed-schema.xml
> [junit4] 2> 2373 T12 oass.IndexSchema.readSchema Reading Solr Schema from /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/conf/managed-schema
> [junit4] 2> 2379 T12 oass.IndexSchema.readSchema [collection1] Schema name=id-and-version-fields-only
> [junit4] 2> 2396 T12 oass.IndexSchema.readSchema unique key field: id
> [junit4] 2> 2398 T12 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/
> [junit4] 2> 2401 T12 x:collection1 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
> [junit4] 2> 2419 T12 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/], dataDir=[null]
> [junit4] 2> 2420 T12 x:collection1 oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
> [junit4] 2> 2457 T12 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001
> [junit4] 2> 2459 T12 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index/
> [junit4] 2> 2460 T12 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index' doesn't exist. Creating new index...
> [junit4] 2> 2470 T12 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index
> [junit4] 2> 2472 T12 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=49.7861328125, floorSegmentMB=1.3154296875, forceMergeDeletesPctAllowed=27.39707383380555, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
> [junit4] 2> 2560 T12 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
> [junit4] 2> 2562 T12 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 2874 T12 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
> [junit4] 2> 2905 T12 x:collection1 oasc.RequestHandlers.applyInitParams WARN INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
> [junit4] 2> 2968 T12 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 2977 T12 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 2979 T12 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 2981 T12 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
> [junit4] 2> 3012 T12 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /config,/schema,/dump,/admin/system,/update,/admin/plugins,/admin/luke,/admin/segments,/update/csv,/admin/ping,/admin/mbeans,/admin/properties,/admin/threads,/update/json/docs,/admin/file,/replication,/update/json,standard,/admin/logging,/get
> [junit4] 2> 3016 T12 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
> [junit4] 2> 3036 T12 x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
> [junit4] 2> 3037 T12 x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
> [junit4] 2> 3051 T12 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
> [junit4] 2> 3052 T12 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
> [junit4] 2> 3056 T12 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@7c491585
> [junit4] 2> 3069 T12 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
> [junit4] 2> 3069 T12 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
> [junit4] 2> 3106 T12 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@6d2fdb53[collection1] main
> [junit4] 2> 3120 T12 x:collection1 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/conf
> [junit4] 2> 3120 T12 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/conf}
> [junit4] 2> 3150 T12 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/tempDir-001/collection1/conf
> [junit4] 2> 3150 T12 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
> [junit4] 2> 3183 T12 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
> [junit4] 2> 3184 T13 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6d2fdb53[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
> [junit4] 2> 3185 T12 x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field
> [junit4] 2> 3185 T12 x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index
> [junit4] 2> 3186 T12 x:collection1 oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot seed version bucket highest value from index
> [junit4] 2> 3186 T12 x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Could not find max version in index or recent updates, using new clock 1502552624681975808
> [junit4] 2> 3190 T12 x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 4 ms to seed version buckets with highest version 1502552624681975808
> [junit4] 2> 3191 T12 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
> [junit4] 2> 3199 T11 oas.SolrTestCaseJ4.initCore ####initCore end
> [junit4] 2> 3216 T11 oas.SolrTestCaseJ4.setUp ###Starting test
> [junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@b70de0f
> [junit4] 2> 3520 T11 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1 (1502552624851845120)]} 0 301
> [junit4] 2> 3538 T11 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2 (1502552625050025984)]} 0 1
> [junit4] 2> 3540 T11 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3 (1502552625053171712)]} 0 0
> [junit4] 2> 3542 T11 C0 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@b70de0f
> [junit4] 2> 3734 T11 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
> [junit4] 2> 3734 T11 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
> [junit4] 2> 3775 T11 C1 oass.SolrIndexSearcher.<init> Opening Searcher@65d06070[collection1] main
> [junit4] 2> 3778 T11 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4] 2> 3779 T13 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@65d06070[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3)))}
> [junit4] 2> 3779 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 237
> [junit4] 2> 3784 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4 (1502552625306927104)]} 0 2
> [junit4] 2> 3787 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5 (1502552625311121408)]} 0 1
> [junit4] 2> 3789 T11 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 3865 T11 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3}
> [junit4] 2> 3867 T11 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
> [junit4] 2> 3878 T11 C1 oass.SolrIndexSearcher.<init> Opening Searcher@ae918c9[collection1] main
> [junit4] 2> 3880 T11 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4] 2> 3880 T13 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ae918c9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3) Uninverting(_2(5.2.0):c2)))}
> [junit4] 2> 3883 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 94
> [junit4] 2> 4033 T11 C1 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=*:*&rows=20&qt=standard&version=2.2&start=0} hits=5 status=0 QTime=143
> [junit4] 2> 4054 T11 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 4063 T11 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3}
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_4,generation=4}
> [junit4] 2> 4064 T11 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
> [junit4] 2> 4068 T11 C1 oass.SolrIndexSearcher.<init> Opening Searcher@7ef4de5d[collection1] main
> [junit4] 2> 4069 T11 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4] 2> 4069 T13 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7ef4de5d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3) Uninverting(_2(5.2.0):c2)))}
> [junit4] 2> 4070 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 16
> [junit4] 2> 4074 T11 oas.SolrTestCaseJ4.tearDown ###Ending test
> [junit4] 2> 4209 T11 C1 oass.SolrIndexSearcher.<init> Opening Searcher@528db148[collection1] realtime
> [junit4] 2> 4210 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1502552625616257024)} 0 133
> [junit4] 2> 4212 T11 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 4215 T11 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_4,generation=4}
> [junit4] 2> commit{dir=NRTCachingDirectory(MMapDirectory@/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56041a58; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_5,generation=5}
> [junit4] 2> 4216 T11 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
> [junit4] 2> 4217 T11 C1 oass.SolrIndexSearcher.<init> Opening Searcher@e9a15d9[collection1] main
> [junit4] 2> 4217 T11 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
> [junit4] 2> 4217 T13 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e9a15d9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
> [junit4] 2> 4223 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSearcherReuse -Dtests.method=test -Dtests.seed=F1A11DF972B907D6 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.locale=sr -Dtests.timezone=GMT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
> [junit4] FAILURE 1.05s | TestSearcherReuse.test <<<
> [junit4] > Throwable #1: java.lang.AssertionError: expected same:<Searcher@ae918c9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3) Uninverting(_2(5.2.0):c2)))}> was not:<Searcher@7ef4de5d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C3) Uninverting(_2(5.2.0):c2)))}>
> [junit4] > at __randomizedtesting.SeedInfo.seed([F1A11DF972B907D6:79F52223DC456A2E]:0)
> [junit4] > at org.apache.solr.search.TestSearcherReuse.assertSearcherHasNotChanged(TestSearcherReuse.java:247)
> [junit4] > at org.apache.solr.search.TestSearcherReuse.test(TestSearcherReuse.java:104)
> [junit4] > at java.lang.Thread.run(Thread.java:745)
> [junit4] 2> 4252 T11 oas.SolrTestCaseJ4.deleteCore ###deleteCore
> [junit4] 2> 4252 T11 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=731222945
> [junit4] 2> 4253 T11 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@b70de0f
> [junit4] 2> 4254 T11 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=364,transaction_logs_total_number=3}
> [junit4] 2> 4254 T11 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
> [junit4] 2> 4254 T11 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
> [junit4] 2> 4255 T11 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
> [junit4] 2> 4256 T11 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
> [junit4] 2> 4256 T11 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
> [junit4] 2> 4257 T11 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001 [CachedDir<<refCount=0;path=/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001;done=false>>]
> [junit4] 2> 4257 T11 oasc.CachingDirectoryFactory.close Closing directory: /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001
> [junit4] 2> 4258 T11 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index [CachedDir<<refCount=0;path=/Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index;done=false>>]
> [junit4] 2> 4258 T11 oasc.CachingDirectoryFactory.close Closing directory: /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002/init-core-data-001/index
> [junit4] 2> NOTE: leaving temporary files on disk at: /Users/sarowe/svn/lucene/dev/branches/lucene_solr_5_2/solr/build/solr-core/test/J0/temp/solr.search.TestSearcherReuse F1A11DF972B907D6-002
> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=sr, timezone=GMT
> [junit4] 2> NOTE: Mac OS X 10.10.3 x86_64/Oracle Corporation 1.7.0_71 (64-bit)/cpus=8,threads=1,free=200793376,total=255852544
> [junit4] 2> NOTE: All tests run in this JVM: [TestSearcherReuse]
> [junit4] Completed [1/1] in 4.51s, 1 test, 1 failure <<< FAILURES!
> [junit4]
> [junit4]
> [junit4] Tests with failures:
> [junit4] - org.apache.solr.search.TestSearcherReuse.test
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org