lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Steve Rowe (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (SOLR-7611) TestSearcherReuse failure
Date Sat, 30 May 2015 02:26:17 GMT

     [ https://issues.apache.org/jira/browse/SOLR-7611?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]

Steve Rowe updated SOLR-7611:
-----------------------------
    Attachment: typescript

Hoss pointed out that succeeding with the same seeds that repro post-LUCENE-6505 could be
false negatives, since randomization changes from the commit could change other random variables.

So I ran the test 100 times at r1682300, and there were 7 failures, each of which reproduce
for me:

{noformat}
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSearcherReuse -Dtests.method=test
-Dtests.seed=9D29262298F82DFC -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.locale=ar_EG -Dtests.timezone=America/El_Salvador -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSearcherReuse -Dtests.method=test
-Dtests.seed=E942A8CBAD0211E0 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.locale=sr_RS -Dtests.timezone=Africa/El_Aaiun -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSearcherReuse -Dtests.method=test
-Dtests.seed=D65A0D59437ED0CC -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.locale=in -Dtests.timezone=Etc/GMT+4 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSearcherReuse -Dtests.method=test
-Dtests.seed=DDDB4AA402826B6E -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.locale=ar_YE -Dtests.timezone=America/Curacao -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSearcherReuse -Dtests.method=test
-Dtests.seed=6EF8BC952C50ED57 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.locale=ru -Dtests.timezone=Asia/Riyadh89 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSearcherReuse -Dtests.method=test
-Dtests.seed=C1B453484C709EEE -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.locale=th_TH -Dtests.timezone=Africa/Khartoum -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSearcherReuse -Dtests.method=test
-Dtests.seed=7D275B133B6C20B -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.locale=mt
-Dtests.timezone=Canada/Mountain -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
{noformat}

All 7 failures are at line 104 of TestSearcherReuse.java, right after the first empty commit.

The attached {{typescript}} contains the logs from all 100 runs.

> 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, typescript
>
>
> {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


Mime
View raw message