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/03/31 17:25:27 UTC
[JENKINS] Lucene-Solr-4.7-Linux (64bit/ibm-j9-jdk6) - Build # 60 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.7-Linux/60/
Java: 64bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
2 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([9AF9C659C05E0D30:C1C93D91C319AE4C]: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:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
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:761)
REGRESSION: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testMultiThreaded
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([9AF9C659C05E0D30:FB6C1CB9288F07C6]: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.testMultiThreaded(SignatureUpdateProcessorFactoryTest.java:222)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
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:761)
Build Log:
[...truncated 10427 lines...]
[junit4] Suite: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest
[junit4] 2> 839884 T2934 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true)
[junit4] 2> 839885 T2934 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752
[junit4] 2> 839896 T2934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 839897 T2934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 839898 T2934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 839944 T2934 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 840021 T2934 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 840023 T2934 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 840034 T2934 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 840407 T2934 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 840410 T2934 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 840412 T2934 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 840427 T2934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 840431 T2934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 840435 T2934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 840436 T2934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 840436 T2934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 840494 T2934 oasc.CoreContainer.<init> New CoreContainer 1657627341
[junit4] 2> 840495 T2934 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 840496 T2934 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 840497 T2934 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 840497 T2934 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 840498 T2934 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 840499 T2934 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 840499 T2934 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 840500 T2934 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 840500 T2934 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 840501 T2934 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 840505 T2934 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 840506 T2934 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 840506 T2934 oasc.CoreContainer.load Host Name:
[junit4] 2> 840511 T2935 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 840512 T2935 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 840513 T2935 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 840513 T2935 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 840558 T2935 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 840616 T2935 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 840617 T2935 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 840623 T2935 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 840970 T2935 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 840973 T2935 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 840975 T2935 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 840989 T2935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 840993 T2935 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 840997 T2935 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 840998 T2935 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752/
[junit4] 2> 840998 T2935 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fbd7fbd
[junit4] 2> 840999 T2935 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752
[junit4] 2> 841000 T2935 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752/index/
[junit4] 2> 841001 T2935 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752/index' doesn't exist. Creating new index...
[junit4] 2> 841001 T2935 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752/index
[junit4] 2> 841002 T2935 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=9.4853515625, floorSegmentMB=1.8642578125, forceMergeDeletesPctAllowed=0.5045208178120486, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 841003 T2935 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_1,generation=1}
[junit4] 2> 841003 T2935 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 841004 T2935 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 841010 T2935 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 841011 T2935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 841011 T2935 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 841012 T2935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 841013 T2935 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 841013 T2935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 841014 T2935 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 841014 T2935 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> 841015 T2935 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 841016 T2935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 841016 T2935 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 841017 T2935 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 841017 T2935 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 841018 T2935 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 841018 T2935 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 841019 T2935 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 841020 T2935 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 841021 T2935 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 841021 T2935 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 841022 T2935 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 841023 T2935 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 841024 T2935 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 841025 T2935 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 841034 T2935 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 841035 T2935 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 841036 T2935 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 841037 T2935 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 841039 T2935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 841040 T2935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 841041 T2935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 841043 T2935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 841044 T2935 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 841045 T2935 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 841046 T2935 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 841048 T2935 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 841049 T2935 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 841051 T2935 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 841054 T2935 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 841056 T2935 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 841056 T2935 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 841057 T2935 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=60.7021484375, floorSegmentMB=1.9375, forceMergeDeletesPctAllowed=5.989843377866464, segmentsPerTier=22.0, maxCFSSegmentSizeMB=0.78125, noCFSRatio=1.0
[junit4] 2> 841058 T2935 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_1,generation=1}
[junit4] 2> 841059 T2935 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 841059 T2935 oass.SolrIndexSearcher.<init> Opening Searcher@5f045f04[collection1] main
[junit4] 2> 841060 T2935 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 841122 T2935 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 841166 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5f045f04[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 841178 T2935 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 841181 T2934 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 841185 T2934 oas.SolrTestCaseJ4.setUp ###Starting testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> ASYNC NEW_CORE C2939 name=collection1 org.apache.solr.core.SolrCore@50955095
[junit4] 2> 841185 T2934 C2939 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> ASYNC NEW_CORE C2940 name=collection1 org.apache.solr.core.SolrCore@50955095
[junit4] 2> 841196 T2934 C2940 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 11
[junit4] 2> 841200 T2934 C2940 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841201 T2934 C2940 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_2,generation=2}
[junit4] 2> 841201 T2934 C2940 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 841202 T2934 C2940 oass.SolrIndexSearcher.<init> Opening Searcher@35e235e2[collection1] main
[junit4] 2> 841203 T2934 C2940 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841206 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@35e235e2[collection1] main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 841207 T2934 C2940 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 841208 T2934 oas.SolrTestCaseJ4.tearDown ###Ending testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 841216 T2934 oas.SolrTestCaseJ4.setUp ###Starting testNonIndexedSignatureField
[junit4] 2> 841217 T2934 C2940 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 841217 T2934 C2940 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 841219 T2934 C2940 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841220 T2934 C2940 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_3,generation=3}
[junit4] 2> 841221 T2934 C2940 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 841221 T2934 C2940 oass.SolrIndexSearcher.<init> Opening Searcher@b300b30[collection1] main
[junit4] 2> 841222 T2934 C2940 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841225 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b300b30[collection1] main{StandardDirectoryReader(segments_3:3:nrt)}
[junit4] 2> 841226 T2934 C2940 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 841233 T2934 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841235 T2934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_4,generation=4}
[junit4] 2> 841235 T2934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 841237 T2934 oass.SolrIndexSearcher.<init> Opening Searcher@4a3b4a3b[collection1] main
[junit4] 2> 841237 T2934 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841240 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4a3b4a3b[collection1] main{StandardDirectoryReader(segments_4:5:nrt _0(4.7):C2)}
[junit4] 2> 841241 T2934 oas.SolrTestCaseJ4.tearDown ###Ending testNonIndexedSignatureField
[junit4] 2> 841249 T2934 oas.SolrTestCaseJ4.setUp ###Starting testDupeAllFieldsDetection
[junit4] 2> 841250 T2934 C2940 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 841251 T2934 C2940 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 841253 T2934 C2940 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841253 T2934 C2940 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_5,generation=5}
[junit4] 2> 841254 T2934 C2940 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 841255 T2934 C2940 oass.SolrIndexSearcher.<init> Opening Searcher@5ef75ef7[collection1] main
[junit4] 2> 841256 T2934 C2940 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841259 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5ef75ef7[collection1] main{StandardDirectoryReader(segments_5:6:nrt)}
[junit4] 2> 841260 T2934 C2940 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 841266 T2934 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841267 T2934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_6,generation=6}
[junit4] 2> 841268 T2934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 841269 T2934 oass.SolrIndexSearcher.<init> Opening Searcher@61cd61cd[collection1] main
[junit4] 2> 841270 T2934 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841273 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@61cd61cd[collection1] main{StandardDirectoryReader(segments_6:8:nrt _1(4.7):C3)}
[junit4] 2> 841274 T2934 oas.SolrTestCaseJ4.tearDown ###Ending testDupeAllFieldsDetection
[junit4] 2> 841291 T2934 oas.SolrTestCaseJ4.setUp ###Starting testNonStringFieldsValues
[junit4] 2> ASYNC NEW_CORE C2941 name=collection1 org.apache.solr.core.SolrCore@50955095
[junit4] 2> 841292 T2934 C2941 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 841293 T2934 C2941 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 841294 T2934 C2941 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841295 T2934 C2941 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_7,generation=7}
[junit4] 2> 841296 T2934 C2941 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 841297 T2934 C2941 oass.SolrIndexSearcher.<init> Opening Searcher@9ee09ee[collection1] main
[junit4] 2> 841298 T2934 C2941 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841301 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9ee09ee[collection1] main{StandardDirectoryReader(segments_7:9:nrt)}
[junit4] 2> 841302 T2934 C2941 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> 841307 T2934 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 841314 T2934 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841316 T2934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_8,generation=8}
[junit4] 2> 841316 T2934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 841318 T2934 oass.SolrIndexSearcher.<init> Opening Searcher@1ab31ab3[collection1] main
[junit4] 2> 841318 T2934 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841322 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ab31ab3[collection1] main{StandardDirectoryReader(segments_8:11:nrt _2(4.7):C5/1:delGen=1)}
[junit4] 2> 841323 T2934 oas.SolrTestCaseJ4.tearDown ###Ending testNonStringFieldsValues
[junit4] 2> 841331 T2934 oas.SolrTestCaseJ4.setUp ###Starting testDupeDetection
[junit4] 2> 841331 T2934 C2941 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 841332 T2934 C2941 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 841334 T2934 C2941 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841334 T2934 C2941 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_9,generation=9}
[junit4] 2> 841335 T2934 C2941 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 841336 T2934 C2941 oass.SolrIndexSearcher.<init> Opening Searcher@8360836[collection1] main
[junit4] 2> 841337 T2934 C2941 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841340 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8360836[collection1] main{StandardDirectoryReader(segments_9:12:nrt)}
[junit4] 2> 841341 T2934 C2941 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 841346 T2934 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841348 T2934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_a,generation=10}
[junit4] 2> 841349 T2934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 841349 T2934 oass.SolrIndexSearcher.<init> Opening Searcher@35473547[collection1] main
[junit4] 2> 841350 T2934 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841353 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@35473547[collection1] main{StandardDirectoryReader(segments_a:14:nrt _3(4.7):C2)}
[junit4] 2> 841357 T2934 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841359 T2934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_b,generation=11}
[junit4] 2> 841359 T2934 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 841360 T2934 oass.SolrIndexSearcher.<init> Opening Searcher@64eb64eb[collection1] main
[junit4] 2> 841361 T2934 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841364 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64eb64eb[collection1] main{StandardDirectoryReader(segments_b:16:nrt _3(4.7):C2 _4(4.7):C1)}
[junit4] 2> 841365 T2934 oas.SolrTestCaseJ4.tearDown ###Ending testDupeDetection
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testDupeDetection -Dtests.seed=9AF9C659C05E0D30 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sl_SI_PREEURO -Dtests.timezone=Iceland -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.06s J0 | SignatureUpdateProcessorFactoryTest.testDupeDetection <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<3>
[junit4] > at __randomizedtesting.SeedInfo.seed([9AF9C659C05E0D30:C1C93D91C319AE4C]: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:761)
[junit4] 2> 841391 T2934 oas.SolrTestCaseJ4.setUp ###Starting testMultiThreaded
[junit4] 2> ASYNC NEW_CORE C2942 name=collection1 org.apache.solr.core.SolrCore@50955095
[junit4] 2> 841392 T2934 C2942 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 841392 T2934 C2942 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 841394 T2934 C2942 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841395 T2934 C2942 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_c,generation=12}
[junit4] 2> 841395 T2934 C2942 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 841396 T2934 C2942 oass.SolrIndexSearcher.<init> Opening Searcher@20e520e5[collection1] main
[junit4] 2> 841397 T2934 C2942 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841400 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@20e520e5[collection1] main{StandardDirectoryReader(segments_c:17:nrt)}
[junit4] 2> 841401 T2934 C2942 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 841410 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841423 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_c,generation=12}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_d,generation=13}
[junit4] 2> 841424 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
[junit4] 2> 841435 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@5f105f10[collection1] main
[junit4] 2> 841453 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5f105f10[collection1] main{StandardDirectoryReader(segments_d:36:nrt _a(4.7):C11/10:delGen=1 _9(4.7):C12/11:delGen=1)}
[junit4] 2> 841453 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841454 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841466 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_d,generation=13}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_e,generation=14}
[junit4] 2> 841468 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
[junit4] 2> 841480 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@1fef1fef[collection1] main
[junit4] 2> 841484 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841485 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841497 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fef1fef[collection1] main{StandardDirectoryReader(segments_e:52:nrt _e(4.7):C18/15:delGen=1 _f(4.7):C15/14:delGen=1)}
[junit4] 2> 841505 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_e,generation=14}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_f,generation=15}
[junit4] 2> 841506 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
[junit4] 2> 841510 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@67b767b7[collection1] main
[junit4] 2> 841511 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841512 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841514 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_f,generation=15}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_g,generation=16}
[junit4] 2> 841515 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67b767b7[collection1] main{StandardDirectoryReader(segments_f:64:nrt _k(4.7):C17/13:delGen=2)}
[junit4] 2> 841515 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
[junit4] 2> 841518 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@2f2c2f2c[collection1] main
[junit4] 2> 841519 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841520 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841522 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_g,generation=16}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_h,generation=17}
[junit4] 2> 841522 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
[junit4] 2> 841522 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f2c2f2c[collection1] main{StandardDirectoryReader(segments_g:70:nrt _k(4.7):C17/16:delGen=2 _m(4.7):C5/4:delGen=1 _n(4.7):C3/1:delGen=1)}
[junit4] 2> 841524 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@56b856b8[collection1] main
[junit4] 2> 841525 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841526 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841528 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_h,generation=17}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_i,generation=18}
[junit4] 2> 841529 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
[junit4] 2> 841528 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@56b856b8[collection1] main{StandardDirectoryReader(segments_h:73:nrt _k(4.7):C17/16:delGen=2 _o(4.7):C2)}
[junit4] 2> 841530 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@62686268[collection1] main
[junit4] 2> 841531 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841532 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841534 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62686268[collection1] main{StandardDirectoryReader(segments_i:77:nrt _o(4.7):C2 _p(4.7):C1)}
[junit4] 2> 841534 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_i,generation=18}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_j,generation=19}
[junit4] 2> 841535 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
[junit4] 2> 841536 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@27142714[collection1] main
[junit4] 2> 841537 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841538 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841540 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_j,generation=19}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_k,generation=20}
[junit4] 2> 841541 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@27142714[collection1] main{StandardDirectoryReader(segments_j:81:nrt _o(4.7):C2/1:delGen=1 _q(4.7):C1)}
[junit4] 2> 841541 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
[junit4] 2> 841542 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@3a203a2[collection1] main
[junit4] 2> 841543 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841544 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841546 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_k,generation=20}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_l,generation=21}
[junit4] 2> 841547 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
[junit4] 2> 841546 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3a203a2[collection1] main{StandardDirectoryReader(segments_k:85:nrt _o(4.7):C2/1:delGen=1 _r(4.7):C1)}
[junit4] 2> 841548 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@1c3e1c3e[collection1] main
[junit4] 2> 841561 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841563 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841565 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c3e1c3e[collection1] main{StandardDirectoryReader(segments_l:89:nrt _o(4.7):C2/1:delGen=1 _s(4.7):C1)}
[junit4] 2> 841566 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_l,generation=21}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_m,generation=22}
[junit4] 2> 841567 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
[junit4] 2> 841568 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@37523752[collection1] main
[junit4] 2> 841568 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841569 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841569 T2947 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 841570 T2947 oasc.SolrCore.getSearcher WARN [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 841571 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@6a2a6a2a[collection1] main
[junit4] 2> 841571 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841619 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841620 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@37523752[collection1] main{StandardDirectoryReader(segments_m:93:nrt _o(4.7):C2/1:delGen=1 _t(4.7):C1)}
[junit4] 2> 841621 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_m,generation=22}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_n,generation=23}
[junit4] 2> 841622 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
[junit4] 2> 841625 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6a2a6a2a[collection1] main{StandardDirectoryReader(segments_m:93:nrt _o(4.7):C2/1:delGen=1 _t(4.7):C1)}
[junit4] 2> 841627 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@291d291d[collection1] main
[junit4] 2> 841628 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841628 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841631 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_n,generation=23}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_o,generation=24}
[junit4] 2> 841632 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
[junit4] 2> 841632 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@291d291d[collection1] main{StandardDirectoryReader(segments_n:99:nrt _u(4.7):C1 _v(4.7):C1)}
[junit4] 2> 841633 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@33e733e7[collection1] main
[junit4] 2> 841634 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841635 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841638 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@33e733e7[collection1] main{StandardDirectoryReader(segments_o:103:nrt _u(4.7):C1 _w(4.7):C1)}
[junit4] 2> 841638 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_o,generation=24}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_p,generation=25}
[junit4] 2> 841639 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
[junit4] 2> 841640 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@483c483c[collection1] main
[junit4] 2> 841641 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841642 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841647 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_p,generation=25}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_q,generation=26}
[junit4] 2> 841648 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
[junit4] 2> 841647 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@483c483c[collection1] main{StandardDirectoryReader(segments_p:107:nrt _x(4.7):C1)}
[junit4] 2> 841651 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@4c4b4c4b[collection1] main
[junit4] 2> 841653 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841654 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841658 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_q,generation=26}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_r,generation=27}
[junit4] 2> 841659 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4c4b4c4b[collection1] main{StandardDirectoryReader(segments_q:109:nrt _x(4.7):C1 _y(4.7):C1)}
[junit4] 2> 841659 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
[junit4] 2> 841661 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@4ff94ff9[collection1] main
[junit4] 2> 841661 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841662 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841664 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_r,generation=27}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_s,generation=28}
[junit4] 2> 841665 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ff94ff9[collection1] main{StandardDirectoryReader(segments_r:113:nrt _x(4.7):C1 _z(4.7):C1)}
[junit4] 2> 841665 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
[junit4] 2> 841666 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@32ee32ee[collection1] main
[junit4] 2> 841667 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841667 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841670 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_s,generation=28}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_t,generation=29}
[junit4] 2> 841671 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
[junit4] 2> 841671 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@32ee32ee[collection1] main{StandardDirectoryReader(segments_s:117:nrt _x(4.7):C1 _10(4.7):C1)}
[junit4] 2> 841673 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@44344434[collection1] main
[junit4] 2> 841682 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841682 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841683 T2946 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 841686 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44344434[collection1] main{StandardDirectoryReader(segments_t:121:nrt _x(4.7):C1 _11(4.7):C1)}
[junit4] 2> 841688 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@6f9b6f9b[collection1] main
[junit4] 2> 841689 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841689 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841692 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_t,generation=29}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_u,generation=30}
[junit4] 2> 841693 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 30
[junit4] 2> 841693 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6f9b6f9b[collection1] main{StandardDirectoryReader(segments_t:125:nrt _x(4.7):C1 _12(4.7):C1)}
[junit4] 2> 841694 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@2d882d88[collection1] main
[junit4] 2> 841695 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841696 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841698 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_u,generation=30}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_v,generation=31}
[junit4] 2> 841699 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2d882d88[collection1] main{StandardDirectoryReader(segments_u:129:nrt _x(4.7):C1 _13(4.7):C1)}
[junit4] 2> 841699 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 31
[junit4] 2> 841700 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@48a048a0[collection1] main
[junit4] 2> 841700 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841701 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841703 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_v,generation=31}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_w,generation=32}
[junit4] 2> 841704 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 32
[junit4] 2> 841704 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@48a048a0[collection1] main{StandardDirectoryReader(segments_v:133:nrt _x(4.7):C1 _14(4.7):C1)}
[junit4] 2> 841705 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@76a376a3[collection1] main
[junit4] 2> 841705 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841706 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841709 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_w,generation=32}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_x,generation=33}
[junit4] 2> 841710 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 33
[junit4] 2> 841710 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@76a376a3[collection1] main{StandardDirectoryReader(segments_w:137:nrt _x(4.7):C1 _15(4.7):C1)}
[junit4] 2> 841711 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@26fb26fb[collection1] main
[junit4] 2> 841712 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841713 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841715 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_x,generation=33}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_y,generation=34}
[junit4] 2> 841716 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@26fb26fb[collection1] main{StandardDirectoryReader(segments_x:141:nrt _x(4.7):C1 _16(4.7):C1)}
[junit4] 2> 841716 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 34
[junit4] 2> 841717 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@39433943[collection1] main
[junit4] 2> 841717 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841718 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841720 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_y,generation=34}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_z,generation=35}
[junit4] 2> 841721 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 35
[junit4] 2> 841721 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@39433943[collection1] main{StandardDirectoryReader(segments_y:143:nrt _x(4.7):C1 _16(4.7):C1 _17(4.7):C1)}
[junit4] 2> 841722 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@74f074f0[collection1] main
[junit4] 2> 841723 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841723 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841726 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@74f074f0[collection1] main{StandardDirectoryReader(segments_z:147:nrt _x(4.7):C1 _18(4.7):C1)}
[junit4] 2> 841776 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_z,generation=35}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_10,generation=36}
[junit4] 2> 841777 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 36
[junit4] 2> 841777 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@67f067f[collection1] main
[junit4] 2> 841778 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841779 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841781 T2945 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_10,generation=36}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_11,generation=37}
[junit4] 2> 841781 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67f067f[collection1] main{StandardDirectoryReader(segments_10:151:nrt _x(4.7):C1 _19(4.7):C1)}
[junit4] 2> 841781 T2945 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 37
[junit4] 2> 841792 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@6f196f19[collection1] main
[junit4] 2> 841793 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841794 T2946 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841795 T2946 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_11,generation=37}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_12,generation=38}
[junit4] 2> 841796 T2946 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 38
[junit4] 2> 841796 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6f196f19[collection1] main{StandardDirectoryReader(segments_11:153:nrt _x(4.7):C1 _19(4.7):C1 _1a(4.7):C1)}
[junit4] 2> 841798 T2946 oass.SolrIndexSearcher.<init> Opening Searcher@30843084[collection1] main
[junit4] 2> 841799 T2946 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841800 T2947 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841802 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30843084[collection1] main{StandardDirectoryReader(segments_12:159:nrt _19(4.7):C1 _1b(4.7):C1)}
[junit4] 2> 841802 T2947 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_12,generation=38}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@40844084 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49c849c8)),segFN=segments_13,generation=39}
[junit4] 2> 841803 T2947 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 39
[junit4] 2> 841804 T2947 oass.SolrIndexSearcher.<init> Opening Searcher@8f408f4[collection1] main
[junit4] 2> 841805 T2947 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841805 T2945 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841806 T2945 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 841808 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8f408f4[collection1] main{StandardDirectoryReader(segments_13:163:nrt _19(4.7):C1 _1c(4.7):C1)}
[junit4] 2> 841808 T2945 oass.SolrIndexSearcher.<init> Opening Searcher@1d0f1d0f[collection1] main
[junit4] 2> 841809 T2945 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841812 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d0f1d0f[collection1] main{StandardDirectoryReader(segments_13:163:nrt _19(4.7):C1 _1c(4.7):C1)}
[junit4] 2> ASYNC NEW_CORE C2943 name=collection1 org.apache.solr.core.SolrCore@50955095
[junit4] 2> 841813 T2934 C2943 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 841814 T2934 C2943 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 841815 T2934 C2943 oass.SolrIndexSearcher.<init> Opening Searcher@623f623f[collection1] main
[junit4] 2> 841816 T2934 C2943 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 841819 T2936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@623f623f[collection1] main{StandardDirectoryReader(segments_13:163:nrt _19(4.7):C1 _1c(4.7):C1)}
[junit4] 2> 841820 T2934 C2943 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 841822 T2934 oas.SolrTestCaseJ4.tearDown ###Ending testMultiThreaded
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testMultiThreaded -Dtests.seed=9AF9C659C05E0D30 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sl_SI_PREEURO -Dtests.timezone=Iceland -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.44s J0 | SignatureUpdateProcessorFactoryTest.testMultiThreaded <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([9AF9C659C05E0D30:FB6C1CB9288F07C6]:0)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testMultiThreaded(SignatureUpdateProcessorFactoryTest.java:222)
[junit4] > at java.lang.Thread.run(Thread.java:761)
[junit4] 2> 841830 T2934 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 841830 T2934 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1657627341
[junit4] 2> 841831 T2934 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50955095
[junit4] 2> 841854 T2934 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> 841855 T2934 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 841856 T2934 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 841856 T2934 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 841857 T2934 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 841857 T2934 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 841858 T2934 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752/index;done=false>>]
[junit4] 2> 841858 T2934 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752/index
[junit4] 2> 841859 T2934 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752;done=false>>]
[junit4] 2> 841860 T2934 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1396279182752
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=sl_SI_PREEURO, timezone=Iceland
[junit4] 2> NOTE: Linux 3.8.0-37-generic amd64/IBM Corporation 1.6.0 (64-bit)/cpus=8,threads=1,free=47434048,total=94435328
[junit4] 2> NOTE: All tests run in this JVM: [ReturnFieldsTest, LoggingHandlerTest, TestRemoteStreaming, TestNonNRTOpen, TestPostingsSolrHighlighter, ChangedSchemaMergeTest, TestShardHandlerFactory, UpdateParamsTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrXml, SolrInfoMBeanTest, TestMergePolicyConfig, TestDocumentBuilder, TestLRUCache, TestReversedWildcardFilterFactory, TestAnalyzeInfixSuggestions, SimpleFacetsTest, SliceStateTest, TestCloudManagedSchemaAddField, QueryElevationComponentTest, RegexBoostProcessorTest, TestRandomDVFaceting, ClusterStateUpdateTest, PrimitiveFieldTypeTest, DateMathParserTest, TestExtendedDismaxParser, CSVRequestHandlerTest, TestLMJelinekMercerSimilarityFactory, TestCustomSort, TestSolrQueryParser, SchemaVersionSpecificBehaviorTest, SuggesterFSTTest, SolrIndexSplitterTest, DistributedTermsComponentTest, TestSort, TestPerFieldSimilarity, ExternalFileFieldSortTest, TestSolrIndexConfig, AddSchemaFieldsUpdateProcessorFactoryTest, TestInfoStreamLogging, TestWriterPerf, UpdateRequestProcessorFactoryTest, EchoParamsTest, TestQueryUtils, HdfsDirectoryTest, TestLuceneMatchVersion, TestSuggestSpellingConverter, TermsComponentTest, FieldMutatingUpdateProcessorTest, DistributedDebugComponentTest, TestPseudoReturnFields, TestMaxScoreQueryParser, SearchHandlerTest, TestCollationKeyRangeQueries, TestCopyFieldCollectionResource, BasicFunctionalityTest, TestSchemaResource, OverseerCollectionProcessorTest, ShowFileRequestHandlerTest, IndexSchemaTest, TestManagedSchemaFieldResource, SampleTest, BasicDistributedZkTest, CachingDirectoryFactoryTest, TestReplicationHandler, LegacyHTMLStripCharFilterTest, TestAddFieldRealTimeGet, MoreLikeThisHandlerTest, TestLFUCache, ScriptEngineTest, TestSearchPerf, HdfsRecoveryZkTest, URLClassifyProcessorTest, DocValuesTest, TestSimpleQParserPlugin, MBeansHandlerTest, TestLMDirichletSimilarityFactory, AnalysisErrorHandlingTest, DOMUtilTest, BadCopyFieldTest, CursorMarkTest, HdfsLockFactoryTest, SyncSliceTest, TestDistribDocBasedVersion, AliasIntegrationTest, TestFieldResource, TestTrie, TestRecoveryHdfs, BasicDistributedZk2Test, UnloadDistributedZkTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, RecoveryZkTest, TestRandomFaceting, LeaderElectionTest, TestZkChroot, ZkCLITest, SliceStateUpdateTest, TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestFaceting, TestRealTimeGet, TestJoin, HardAutoCommitTest, TestCoreContainer, SolrCoreTest, TestGroupingSearch, DirectUpdateHandlerTest, TestIndexSearcher, HighlighterTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest]
[junit4] Completed on J0 in 2.03s, 6 tests, 2 failures <<< FAILURES!
[...truncated 441 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:452: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/solr/common-build.xml:505: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/lucene/common-build.xml:1278: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.7-Linux/lucene/common-build.xml:911: There were test failures: 365 suites, 1570 tests, 2 failures, 73 ignored (7 assumptions)
Total time: 44 minutes 53 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/ibm-j9-jdk6 -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