You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/01/25 17:18:20 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/ibm-j9-jdk7) - Build # 9132
- Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/9132/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
1 tests failed.
REGRESSION: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection
Error Message:
expected:<1> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<3>
at __randomizedtesting.SeedInfo.seed([5DBF088F5CC147F2:68FF3475F86E48E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:780)
Build Log:
[...truncated 10068 lines...]
[junit4] Suite: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest
[junit4] 2> 155057 T483 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002
[junit4] 2> 155058 T483 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 155059 T483 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 155059 T483 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 155093 T483 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 155139 T483 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 155140 T483 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 155146 T483 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 155452 T483 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 155456 T483 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 155458 T483 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 155465 T483 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 155470 T483 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 155475 T483 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 155476 T483 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 155476 T483 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 155484 T483 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 155485 T483 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 155485 T483 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 155521 T483 oasc.CoreContainer.<init> New CoreContainer 2126426722
[junit4] 2> 155521 T483 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 155522 T483 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 155523 T483 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 155523 T483 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 155523 T483 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 155524 T483 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 155524 T483 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 155524 T483 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 155524 T483 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 155525 T483 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 155527 T483 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 155528 T483 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 155528 T483 oasc.CoreContainer.load Host Name:
[junit4] 2> 155531 T484 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 155531 T484 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 155532 T484 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 155532 T484 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 155564 T484 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 155609 T484 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 155610 T484 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 155615 T484 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 156008 T484 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 156010 T484 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 156012 T484 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 156019 T484 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 156022 T484 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 156026 T484 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 156026 T484 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002/
[junit4] 2> 156026 T484 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c919e12
[junit4] 2> 156027 T484 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002
[junit4] 2> 156027 T484 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002/index/
[junit4] 2> 156028 T484 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002/index' doesn't exist. Creating new index...
[junit4] 2> 156028 T484 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002/index
[junit4] 2> 156028 T484 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=20.9609375, floorSegmentMB=1.90234375, forceMergeDeletesPctAllowed=28.65835861812842, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10586913430428427
[junit4] 2> 156029 T484 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_1,generation=1}
[junit4] 2> 156029 T484 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 156030 T484 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 156034 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 156034 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 156035 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 156035 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 156035 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 156035 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 156036 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 156036 T484 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
[junit4] 2> 156036 T484 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 156037 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 156037 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 156037 T484 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 156038 T484 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 156038 T484 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 156038 T484 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 156039 T484 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 156039 T484 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 156039 T484 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 156040 T484 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 156040 T484 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 156041 T484 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 156041 T484 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 156042 T484 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 156042 T484 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 156042 T484 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 156043 T484 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 156043 T484 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 156044 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 156044 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 156044 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 156045 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 156045 T484 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 156046 T484 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 156046 T484 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 156047 T484 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 156047 T484 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 156048 T484 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 156050 T484 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 156052 T484 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 156052 T484 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 156053 T484 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=954304030, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 156053 T484 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_1,generation=1}
[junit4] 2> 156054 T484 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 156054 T484 oass.SolrIndexSearcher.<init> Opening Searcher@9dce09ed main
[junit4] 2> 156055 T484 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 156068 T484 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 156089 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9dce09ed main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 156094 T484 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 156095 T483 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 156098 T483 oas.SolrTestCaseJ4.setUp ###Starting testDupeDetection
[junit4] 2> ASYNC NEW_CORE C359 name=collection1 org.apache.solr.core.SolrCore@fa388b02
[junit4] 2> 156099 T483 C359 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 156099 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 156103 T483 C359 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 156104 T483 C359 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_2,generation=2}
[junit4] 2> 156104 T483 C359 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 156105 T483 C359 oass.SolrIndexSearcher.<init> Opening Searcher@dbd2bf6f main
[junit4] 2> 156106 T483 C359 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 156107 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dbd2bf6f main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 156108 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 157158 T483 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157224 T483 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_3,generation=3}
[junit4] 2> 157224 T483 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 157225 T483 oass.SolrIndexSearcher.<init> Opening Searcher@f50122aa main
[junit4] 2> 157226 T483 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157227 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f50122aa main{StandardDirectoryReader(segments_3:4:nrt _0(4.7):c2)}
[junit4] 2> 157230 T483 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157233 T483 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_4,generation=4}
[junit4] 2> 157233 T483 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 157234 T483 oass.SolrIndexSearcher.<init> Opening Searcher@548cb55d main
[junit4] 2> 157235 T483 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157236 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@548cb55d main{StandardDirectoryReader(segments_4:6:nrt _0(4.7):c2 _1(4.7):c1)}
[junit4] 2> 157237 T483 oas.SolrTestCaseJ4.tearDown ###Ending testDupeDetection
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testDupeDetection -Dtests.seed=5DBF088F5CC147F2 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_CO -Dtests.timezone=EST -Dtests.file.encoding=UTF-8
[junit4] FAILURE 1.15s J1 | SignatureUpdateProcessorFactoryTest.testDupeDetection <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<3>
[junit4] > at __randomizedtesting.SeedInfo.seed([5DBF088F5CC147F2:68FF3475F86E48E]:0)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 157247 T483 oas.SolrTestCaseJ4.setUp ###Starting testNonIndexedSignatureField
[junit4] 2> 157552 T483 C359 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 157553 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 306
[junit4] 2> 157768 T483 C359 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157769 T483 C359 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_5,generation=5}
[junit4] 2> 157769 T483 C359 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 157770 T483 C359 oass.SolrIndexSearcher.<init> Opening Searcher@a8c01b01 main
[junit4] 2> 157770 T483 C359 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157772 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a8c01b01 main{StandardDirectoryReader(segments_5:7:nrt)}
[junit4] 2> 157773 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 157777 T483 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157779 T483 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_6,generation=6}
[junit4] 2> 157779 T483 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 157781 T483 oass.SolrIndexSearcher.<init> Opening Searcher@1c385695 main
[junit4] 2> 157781 T483 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157783 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c385695 main{StandardDirectoryReader(segments_6:9:nrt _2(4.7):c2)}
[junit4] 2> 157783 T483 oas.SolrTestCaseJ4.tearDown ###Ending testNonIndexedSignatureField
[junit4] 2> 157787 T483 oas.SolrTestCaseJ4.setUp ###Starting testDupeAllFieldsDetection
[junit4] 2> 157788 T483 C359 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 157789 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 157790 T483 C359 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157791 T483 C359 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_7,generation=7}
[junit4] 2> 157792 T483 C359 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 157792 T483 C359 oass.SolrIndexSearcher.<init> Opening Searcher@3643e5cb main
[junit4] 2> 157793 T483 C359 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157795 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3643e5cb main{StandardDirectoryReader(segments_7:10:nrt)}
[junit4] 2> 157795 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 157802 T483 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157804 T483 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_8,generation=8}
[junit4] 2> 157804 T483 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 157805 T483 oass.SolrIndexSearcher.<init> Opening Searcher@9ac4d802 main
[junit4] 2> 157806 T483 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157808 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9ac4d802 main{StandardDirectoryReader(segments_8:12:nrt _3(4.7):c3)}
[junit4] 2> 157808 T483 oas.SolrTestCaseJ4.tearDown ###Ending testDupeAllFieldsDetection
[junit4] 2> 157812 T483 oas.SolrTestCaseJ4.setUp ###Starting testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 157813 T483 C359 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 157814 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 157815 T483 C359 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157816 T483 C359 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_9,generation=9}
[junit4] 2> 157816 T483 C359 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 157817 T483 C359 oass.SolrIndexSearcher.<init> Opening Searcher@aed42a78 main
[junit4] 2> 157818 T483 C359 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157820 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@aed42a78 main{StandardDirectoryReader(segments_9:13:nrt)}
[junit4] 2> 157820 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 157822 T483 oas.SolrTestCaseJ4.tearDown ###Ending testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 157826 T483 oas.SolrTestCaseJ4.setUp ###Starting testNonStringFieldsValues
[junit4] 2> 157827 T483 C359 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 157827 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 157829 T483 C359 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157830 T483 C359 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_a,generation=10}
[junit4] 2> 157830 T483 C359 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 157831 T483 C359 oass.SolrIndexSearcher.<init> Opening Searcher@a0c83ac9 main
[junit4] 2> 157832 T483 C359 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157833 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a0c83ac9 main{StandardDirectoryReader(segments_a:14:nrt)}
[junit4] 2> 157834 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 157839 T483 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 157985 T483 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157987 T483 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_b,generation=11}
[junit4] 2> 157987 T483 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 157988 T483 oass.SolrIndexSearcher.<init> Opening Searcher@86eb8c0e main
[junit4] 2> 157989 T483 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 157990 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@86eb8c0e main{StandardDirectoryReader(segments_b:16:nrt _4(4.7):c5/1:delGen=1)}
[junit4] 2> 157991 T483 oas.SolrTestCaseJ4.tearDown ###Ending testNonStringFieldsValues
[junit4] 2> 157995 T483 oas.SolrTestCaseJ4.setUp ###Starting testMultiThreaded
[junit4] 2> 157995 T483 C359 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 157996 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 157997 T483 C359 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 157998 T483 C359 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_c,generation=12}
[junit4] 2> 157998 T483 C359 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 157999 T483 C359 oass.SolrIndexSearcher.<init> Opening Searcher@48df83bd main
[junit4] 2> 158000 T483 C359 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158001 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@48df83bd main{StandardDirectoryReader(segments_c:17:nrt)}
[junit4] 2> 158002 T483 C359 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 158010 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158051 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_c,generation=12}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_d,generation=13}
[junit4] 2> 158051 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
[junit4] 2> 158088 T495 oass.SolrIndexSearcher.<init> Opening Searcher@1f64d6b8 main
[junit4] 2> 158089 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158089 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158090 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f64d6b8 main{StandardDirectoryReader(segments_d:41:nrt _c(4.7):c28/24:delGen=1)}
[junit4] 2> 158108 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_d,generation=13}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_e,generation=14}
[junit4] 2> 158109 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
[junit4] 2> 158113 T496 oass.SolrIndexSearcher.<init> Opening Searcher@54485ba2 main
[junit4] 2> 158114 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158114 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158116 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@54485ba2 main{StandardDirectoryReader(segments_e:54:nrt _h(4.7):c3 _i(4.7):c1)}
[junit4] 2> 158119 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_e,generation=14}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_f,generation=15}
[junit4] 2> 158119 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
[junit4] 2> 158122 T494 oass.SolrIndexSearcher.<init> Opening Searcher@a4cab1d1 main
[junit4] 2> 158122 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158123 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158124 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a4cab1d1 main{StandardDirectoryReader(segments_f:62:nrt _j(4.7):c6/5:delGen=1 _k(4.7):c5/2:delGen=1)}
[junit4] 2> 158126 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_f,generation=15}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_g,generation=16}
[junit4] 2> 158126 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
[junit4] 2> 158129 T495 oass.SolrIndexSearcher.<init> Opening Searcher@74148c6 main
[junit4] 2> 158130 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158130 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158132 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@74148c6 main{StandardDirectoryReader(segments_g:69:nrt _k(4.7):c5/4:delGen=2 _l(4.7):c3/2:delGen=1 _m(4.7):c4/2:delGen=1)}
[junit4] 2> 158133 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_g,generation=16}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_h,generation=17}
[junit4] 2> 158134 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
[junit4] 2> 158138 T496 oass.SolrIndexSearcher.<init> Opening Searcher@e483937c main
[junit4] 2> 158138 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158138 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158139 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e483937c main{StandardDirectoryReader(segments_h:77:nrt _l(4.7):c3/2:delGen=2 _o(4.7):c4/1:delGen=1)}
[junit4] 2> 158141 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_h,generation=17}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_i,generation=18}
[junit4] 2> 158142 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
[junit4] 2> 158142 T494 oass.SolrIndexSearcher.<init> Opening Searcher@68b0424c main
[junit4] 2> 158143 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158143 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158145 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@68b0424c main{StandardDirectoryReader(segments_i:79:nrt _l(4.7):c3/2:delGen=2 _o(4.7):c4/2:delGen=2 _p(4.7):c1)}
[junit4] 2> 158146 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_i,generation=18}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_j,generation=19}
[junit4] 2> 158146 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
[junit4] 2> 158146 T495 oass.SolrIndexSearcher.<init> Opening Searcher@f7097666 main
[junit4] 2> 158147 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158147 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158149 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f7097666 main{StandardDirectoryReader(segments_j:83:nrt _l(4.7):c3/2:delGen=2 _o(4.7):c4/3:delGen=3 _q(4.7):c1)}
[junit4] 2> 158150 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_j,generation=19}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_k,generation=20}
[junit4] 2> 158151 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
[junit4] 2> 158151 T496 oass.SolrIndexSearcher.<init> Opening Searcher@6fde0089 main
[junit4] 2> 158152 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158152 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158153 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6fde0089 main{StandardDirectoryReader(segments_k:87:nrt _o(4.7):c4/3:delGen=3 _r(4.7):c1)}
[junit4] 2> 158155 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_k,generation=20}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_l,generation=21}
[junit4] 2> 158156 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
[junit4] 2> 158159 T494 oass.SolrIndexSearcher.<init> Opening Searcher@21adbfc7 main
[junit4] 2> 158160 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158160 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158161 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_l,generation=21}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_m,generation=22}
[junit4] 2> 158161 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
[junit4] 2> 158161 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@21adbfc7 main{StandardDirectoryReader(segments_l:93:nrt _o(4.7):c4/3:delGen=3 _r(4.7):c1 _t(4.7):c1)}
[junit4] 2> 158162 T495 oass.SolrIndexSearcher.<init> Opening Searcher@2194bfcd main
[junit4] 2> 158162 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158162 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158163 T496 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 158164 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2194bfcd main{StandardDirectoryReader(segments_l:93:nrt _o(4.7):c4/3:delGen=3 _r(4.7):c1 _t(4.7):c1)}
[junit4] 2> 158167 T496 oass.SolrIndexSearcher.<init> Opening Searcher@ec6aa0cf main
[junit4] 2> 158167 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158168 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158169 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ec6aa0cf main{StandardDirectoryReader(segments_m:98:nrt _r(4.7):c1 _u(4.7):c1)}
[junit4] 2> 158170 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_m,generation=22}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_n,generation=23}
[junit4] 2> 158170 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
[junit4] 2> 158171 T494 oass.SolrIndexSearcher.<init> Opening Searcher@5e130a49 main
[junit4] 2> 158171 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158172 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158173 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5e130a49 main{StandardDirectoryReader(segments_n:101:nrt _u(4.7):c1 _v(4.7):c1)}
[junit4] 2> 158174 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_n,generation=23}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_o,generation=24}
[junit4] 2> 158175 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
[junit4] 2> 158175 T495 oass.SolrIndexSearcher.<init> Opening Searcher@4efa4326 main
[junit4] 2> 158176 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158176 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158177 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4efa4326 main{StandardDirectoryReader(segments_o:105:nrt _w(4.7):c1)}
[junit4] 2> 158179 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_o,generation=24}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_p,generation=25}
[junit4] 2> 158179 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
[junit4] 2> 158180 T496 oass.SolrIndexSearcher.<init> Opening Searcher@a458956a main
[junit4] 2> 158180 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158180 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158182 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a458956a main{StandardDirectoryReader(segments_p:109:nrt _x(4.7):c1)}
[junit4] 2> 158183 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_p,generation=25}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_q,generation=26}
[junit4] 2> 158183 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
[junit4] 2> 158184 T494 oass.SolrIndexSearcher.<init> Opening Searcher@c0798032 main
[junit4] 2> 158184 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158184 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158185 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c0798032 main{StandardDirectoryReader(segments_q:111:nrt _x(4.7):c1 _y(4.7):c1)}
[junit4] 2> 158186 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_q,generation=26}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_r,generation=27}
[junit4] 2> 158187 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
[junit4] 2> 158187 T495 oass.SolrIndexSearcher.<init> Opening Searcher@600f72b0 main
[junit4] 2> 158188 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158188 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158189 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@600f72b0 main{StandardDirectoryReader(segments_r:115:nrt _z(4.7):c1)}
[junit4] 2> 158191 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_r,generation=27}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_s,generation=28}
[junit4] 2> 158191 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
[junit4] 2> 158192 T496 oass.SolrIndexSearcher.<init> Opening Searcher@c498ca9b main
[junit4] 2> 158192 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158193 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158194 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c498ca9b main{StandardDirectoryReader(segments_s:119:nrt _10(4.7):c1)}
[junit4] 2> 158195 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_s,generation=28}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_t,generation=29}
[junit4] 2> 158196 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
[junit4] 2> 158196 T494 oass.SolrIndexSearcher.<init> Opening Searcher@a1ac66d2 main
[junit4] 2> 158197 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158197 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158198 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a1ac66d2 main{StandardDirectoryReader(segments_t:121:nrt _10(4.7):c1 _11(4.7):c1)}
[junit4] 2> 158199 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_t,generation=29}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_u,generation=30}
[junit4] 2> 158200 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 30
[junit4] 2> 158200 T495 oass.SolrIndexSearcher.<init> Opening Searcher@1ec6d335 main
[junit4] 2> 158200 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158201 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158202 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ec6d335 main{StandardDirectoryReader(segments_u:125:nrt _12(4.7):c1)}
[junit4] 2> 158203 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_u,generation=30}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_v,generation=31}
[junit4] 2> 158203 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 31
[junit4] 2> 158204 T496 oass.SolrIndexSearcher.<init> Opening Searcher@1d687ab2 main
[junit4] 2> 158204 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158205 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158206 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d687ab2 main{StandardDirectoryReader(segments_v:129:nrt _13(4.7):c1)}
[junit4] 2> 158207 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_v,generation=31}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_w,generation=32}
[junit4] 2> 158208 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 32
[junit4] 2> 158208 T494 oass.SolrIndexSearcher.<init> Opening Searcher@21bc93f8 main
[junit4] 2> 158209 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158209 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158210 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@21bc93f8 main{StandardDirectoryReader(segments_w:133:nrt _14(4.7):c1)}
[junit4] 2> 158212 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_w,generation=32}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_x,generation=33}
[junit4] 2> 158213 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 33
[junit4] 2> 158215 T495 oass.SolrIndexSearcher.<init> Opening Searcher@23baf9dc main
[junit4] 2> 158215 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158215 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158216 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_x,generation=33}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_y,generation=34}
[junit4] 2> 158216 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 34
[junit4] 2> 158217 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23baf9dc main{StandardDirectoryReader(segments_x:141:nrt _16(4.7):c1)}
[junit4] 2> 158217 T496 oass.SolrIndexSearcher.<init> Opening Searcher@d0b7127a main
[junit4] 2> 158218 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158218 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158218 T494 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 158219 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d0b7127a main{StandardDirectoryReader(segments_x:141:nrt _16(4.7):c1)}
[junit4] 2> 158222 T494 oass.SolrIndexSearcher.<init> Opening Searcher@faa07a47 main
[junit4] 2> 158222 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158223 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158224 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@faa07a47 main{StandardDirectoryReader(segments_y:144:nrt _16(4.7):c1 _17(4.7):c1)}
[junit4] 2> 158225 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_y,generation=34}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_z,generation=35}
[junit4] 2> 158226 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 35
[junit4] 2> 158226 T495 oass.SolrIndexSearcher.<init> Opening Searcher@28932c2b main
[junit4] 2> 158227 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158227 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158228 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28932c2b main{StandardDirectoryReader(segments_z:147:nrt _16(4.7):c1 _18(4.7):c1)}
[junit4] 2> 158230 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_z,generation=35}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_10,generation=36}
[junit4] 2> 158230 T496 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 36
[junit4] 2> 158231 T496 oass.SolrIndexSearcher.<init> Opening Searcher@912af759 main
[junit4] 2> 158231 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158231 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158232 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@912af759 main{StandardDirectoryReader(segments_10:151:nrt _16(4.7):c1 _19(4.7):c1)}
[junit4] 2> 158236 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_10,generation=36}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_11,generation=37}
[junit4] 2> 158236 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 37
[junit4] 2> 158239 T494 oass.SolrIndexSearcher.<init> Opening Searcher@364a1382 main
[junit4] 2> 158239 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158240 T495 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158240 T495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_11,generation=37}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_12,generation=38}
[junit4] 2> 158240 T495 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 38
[junit4] 2> 158241 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@364a1382 main{StandardDirectoryReader(segments_11:159:nrt _1b(4.7):c1)}
[junit4] 2> 158241 T495 oass.SolrIndexSearcher.<init> Opening Searcher@c46c9ed5 main
[junit4] 2> 158242 T495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158242 T496 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158243 T496 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 158243 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c46c9ed5 main{StandardDirectoryReader(segments_11:159:nrt _1b(4.7):c1)}
[junit4] 2> 158246 T496 oass.SolrIndexSearcher.<init> Opening Searcher@8cb7b68 main
[junit4] 2> 158246 T496 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158247 T494 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158247 T494 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_12,generation=38}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9a7a749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cbfba418)),segFN=segments_13,generation=39}
[junit4] 2> 158247 T494 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 39
[junit4] 2> 158248 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8cb7b68 main{StandardDirectoryReader(segments_12:163:nrt _1c(4.7):c1)}
[junit4] 2> 158248 T494 oass.SolrIndexSearcher.<init> Opening Searcher@7018cb6e main
[junit4] 2> 158248 T494 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158250 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7018cb6e main{StandardDirectoryReader(segments_12:163:nrt _1c(4.7):c1)}
[junit4] 2> ASYNC NEW_CORE C360 name=collection1 org.apache.solr.core.SolrCore@fa388b02
[junit4] 2> 158250 T483 C360 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 158251 T483 C360 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 158252 T483 C360 oass.SolrIndexSearcher.<init> Opening Searcher@eebb32cf main
[junit4] 2> 158252 T483 C360 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 158254 T485 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@eebb32cf main{StandardDirectoryReader(segments_12:163:nrt _1c(4.7):c1)}
[junit4] 2> 158255 T483 C360 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 158256 T483 oas.SolrTestCaseJ4.tearDown ###Ending testMultiThreaded
[junit4] 2> 158258 T483 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 158258 T483 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2126426722
[junit4] 2> 158259 T483 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@fa388b02
[junit4] 2> 158279 T483 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=42,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=253,cumulative_deletesById=0,cumulative_deletesByQuery=6,cumulative_errors=0}
[junit4] 2> 158279 T483 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 158280 T483 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 158280 T483 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 158280 T483 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 158281 T483 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 158281 T483 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002;done=false>>]
[junit4] 2> 158282 T483 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002
[junit4] 2> 158282 T483 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002/index;done=false>>]
[junit4] 2> 158282 T483 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1390666233002/index
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=DefaultSimilarity, locale=es_CO, timezone=EST
[junit4] 2> NOTE: Linux 3.8.0-35-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=119608264,total=158531584
[junit4] 2> NOTE: All tests run in this JVM: [TestMaxScoreQueryParser, DirectSolrConnectionTest, TestQueryTypes, TestDFRSimilarityFactory, DirectSolrSpellCheckerTest, NumericFieldsTest, TestDistributedGrouping, DirectUpdateHandlerTest, TestIBSimilarityFactory, TestSolrXmlPersistence, CoreAdminCreateDiscoverTest, DefaultValueUpdateProcessorTest, CollectionsAPIDistributedZkTest, TestStressReorder, TestComponentsName, ZkNodePropsTest, PreAnalyzedFieldTest, PluginInfoTest, MigrateRouteKeyTest, LeaderElectionIntegrationTest, TestHighFrequencyDictionaryFactory, OpenCloseCoreStressTest, CurrencyFieldOpenExchangeTest, TestMultiCoreConfBootstrap, SolrCoreCheckLockOnStartupTest, DocValuesMultiTest, PrimUtilsTest, TestXIncludeConfig, SignatureUpdateProcessorFactoryTest]
[junit4] Completed on J1 in 3.25s, 6 tests, 1 failure <<< FAILURES!
[...truncated 911 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:452: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1308: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:941: There were test failures: 355 suites, 1547 tests, 1 failure, 68 ignored (7 assumptions)
Total time: 33 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure