Details
-
Bug
-
Status: Resolved
-
Major
-
Resolution: Fixed
-
5.2
-
None
-
None
-
None
Description
[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)
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:
ant test -Dtestcase=TestSearcherReuse -Dtests.seed=F1A11DF972B907D6 -Dtests.multiplier=2 -Dtests.nightly=true
Full log:
[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
