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/07 14:46:34 UTC

[JENKINS] Lucene-Solr-4.x-Linux (64bit/ibm-j9-jdk7) - Build # 8898 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/8898/
Java: 64bit/ibm-j9-jdk7 -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.testMultiThreaded

Error Message:
expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
	at __randomizedtesting.SeedInfo.seed([2F3D237ED0942F2D:4EA8F99E384525DB]: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: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)


REGRESSION:  org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection

Error Message:
expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
	at __randomizedtesting.SeedInfo.seed([2F3D237ED0942F2D:740DD8B6D3D38C51]: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 10520 lines...]
   [junit4] Suite: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest
   [junit4]   2> 538936 T1130 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-1389102310406
   [junit4]   2> 538937 T1130 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> 538938 T1130 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> 538938 T1130 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> 538971 T1130 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 539011 T1130 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 539012 T1130 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
   [junit4]   2> 539017 T1130 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 539299 T1130 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 539302 T1130 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 539303 T1130 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 539309 T1130 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 539313 T1130 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 539316 T1130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 539316 T1130 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> 539316 T1130 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> 539323 T1130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 539323 T1130 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> 539323 T1130 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> 539372 T1130 oasc.CoreContainer.<init> New CoreContainer 95337965
   [junit4]   2> 539372 T1130 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> 539373 T1130 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 539373 T1130 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 539374 T1130 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 539374 T1130 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 539374 T1130 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 539374 T1130 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 539375 T1130 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 539375 T1130 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 539375 T1130 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 539378 T1130 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 539379 T1130 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 539379 T1130 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 539382 T1131 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> 539382 T1131 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> 539383 T1131 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> 539383 T1131 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> 539417 T1131 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 539463 T1131 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 539464 T1131 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
   [junit4]   2> 539469 T1131 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 539748 T1131 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 539750 T1131 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 539751 T1131 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 539758 T1131 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 539762 T1131 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 539765 T1131 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 539766 T1131 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-1389102310406/
   [junit4]   2> 539766 T1131 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@84be4c7d
   [junit4]   2> 539767 T1131 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1389102310406
   [junit4]   2> 539767 T1131 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-1389102310406/index/
   [junit4]   2> 539767 T1131 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-1389102310406/index' doesn't exist. Creating new index...
   [junit4]   2> 539768 T1131 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1389102310406/index
   [junit4]   2> 539768 T1131 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=55.603515625, floorSegmentMB=0.4970703125, forceMergeDeletesPctAllowed=8.499271474326418, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 539769 T1131 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_1,generation=1}
   [junit4]   2> 539769 T1131 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 539770 T1131 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 539771 T1131 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 539772 T1131 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 539772 T1131 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 539772 T1131 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 539773 T1131 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 539773 T1131 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 539773 T1131 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 539774 T1131 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> 539774 T1131 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 539774 T1131 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 539775 T1131 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 539775 T1131 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 539775 T1131 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 539776 T1131 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 539776 T1131 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 539776 T1131 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 539777 T1131 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 539777 T1131 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 539777 T1131 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 539778 T1131 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 539778 T1131 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 539778 T1131 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 539779 T1131 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 539779 T1131 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 539779 T1131 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 539780 T1131 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 539780 T1131 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 539781 T1131 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 539781 T1131 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 539781 T1131 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 539782 T1131 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 539782 T1131 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 539782 T1131 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 539783 T1131 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 539783 T1131 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 539784 T1131 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 539785 T1131 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 539786 T1131 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 539788 T1131 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 539788 T1131 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 539788 T1131 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=19.0400390625, floorSegmentMB=1.3173828125, forceMergeDeletesPctAllowed=12.284346986496235, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 539789 T1131 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_1,generation=1}
   [junit4]   2> 539789 T1131 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 539790 T1131 oass.SolrIndexSearcher.<init> Opening Searcher@98ce8f20 main
   [junit4]   2> 539790 T1131 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 539980 T1131 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 539997 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@98ce8f20 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 540007 T1131 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 540009 T1130 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 540012 T1130 oas.SolrTestCaseJ4.setUp ###Starting testMultiThreaded
   [junit4]   2> ASYNC  NEW_CORE C844 name=collection1 org.apache.solr.core.SolrCore@760957fd
   [junit4]   2> 540017 T1130 C844 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 540017 T1130 C844 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
   [junit4]   2> 540025 T1130 C844 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540026 T1130 C844 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_2,generation=2}
   [junit4]   2> 540026 T1130 C844 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 540027 T1130 C844 oass.SolrIndexSearcher.<init> Opening Searcher@e2a0c328 main
   [junit4]   2> 540027 T1130 C844 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540028 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e2a0c328 main{StandardDirectoryReader(segments_2:2:nrt)}
   [junit4]   2> 540029 T1130 C844 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 540035 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540046 T1143 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_3,generation=3}
   [junit4]   2> 540047 T1143 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 540067 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@c684efe0 main
   [junit4]   2> 540068 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540069 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540069 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c684efe0 main{StandardDirectoryReader(segments_3:16:nrt _3(4.7):C13/12:delGen=1)}
   [junit4]   2> 540090 T1141 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_4,generation=4}
   [junit4]   2> 540091 T1141 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 540104 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@568831fd main
   [junit4]   2> 540105 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540106 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@568831fd main{StandardDirectoryReader(segments_4:28:nrt _7(4.7):C25/23:delGen=1)}
   [junit4]   2> 540106 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540118 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_4,generation=4}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_5,generation=5}
   [junit4]   2> 540119 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
   [junit4]   2> 540126 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@1a9e2bae main
   [junit4]   2> 540126 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540127 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540127 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a9e2bae main{StandardDirectoryReader(segments_5:38:nrt _a(4.7):C18/17:delGen=2)}
   [junit4]   2> 540127 T1143 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_5,generation=5}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_6,generation=6}
   [junit4]   2> 540128 T1143 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
   [junit4]   2> 540128 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@e3d93555 main
   [junit4]   2> 540129 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540129 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540129 T1141 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 540130 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e3d93555 main{StandardDirectoryReader(segments_5:38:nrt _a(4.7):C18/17:delGen=2)}
   [junit4]   2> 540132 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@544bda95 main
   [junit4]   2> 540133 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540133 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540134 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@544bda95 main{StandardDirectoryReader(segments_6:41:nrt _a(4.7):C18/17:delGen=2 _c(4.7):C1)}
   [junit4]   2> 540136 T1143 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_6,generation=6}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_7,generation=7}
   [junit4]   2> 540136 T1143 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
   [junit4]   2> 540139 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@6127640 main
   [junit4]   2> 540140 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540140 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540141 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_7,generation=7}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_8,generation=8}
   [junit4]   2> 540141 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6127640 main{StandardDirectoryReader(segments_7:48:nrt _e(4.7):C1)}
   [junit4]   2> 540141 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
   [junit4]   2> 540142 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@fd23c65f main
   [junit4]   2> 540142 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540143 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540144 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fd23c65f main{StandardDirectoryReader(segments_7:48:nrt _e(4.7):C1)}
   [junit4]   2> 540145 T1141 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_8,generation=8}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_9,generation=9}
   [junit4]   2> 540145 T1141 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
   [junit4]   2> 540148 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@58b307c1 main
   [junit4]   2> 540149 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540149 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540150 T1143 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_9,generation=9}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_a,generation=10}
   [junit4]   2> 540150 T1143 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
   [junit4]   2> 540150 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@58b307c1 main{StandardDirectoryReader(segments_9:54:nrt _f(4.7):C1 _g(4.7):C1)}
   [junit4]   2> 540151 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@fbc97ddd main
   [junit4]   2> 540151 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540151 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540152 T1142 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 540152 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@fbc97ddd main{StandardDirectoryReader(segments_9:54:nrt _f(4.7):C1 _g(4.7):C1)}
   [junit4]   2> 540155 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@de35be7f main
   [junit4]   2> 540155 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540156 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540156 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@de35be7f main{StandardDirectoryReader(segments_a:59:nrt _h(4.7):C1)}
   [junit4]   2> 540158 T1141 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_a,generation=10}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_b,generation=11}
   [junit4]   2> 540158 T1141 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
   [junit4]   2> 540161 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@c04e2602 main
   [junit4]   2> 540161 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540161 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540162 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c04e2602 main{StandardDirectoryReader(segments_b:64:nrt _i(4.7):C1 _j(4.7):C1)}
   [junit4]   2> 540162 T1143 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_b,generation=11}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_c,generation=12}
   [junit4]   2> 540163 T1143 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
   [junit4]   2> 540163 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@2c0bbd3 main
   [junit4]   2> 540163 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540164 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540165 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c0bbd3 main{StandardDirectoryReader(segments_b:64:nrt _i(4.7):C1 _j(4.7):C1)}
   [junit4]   2> 540166 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_c,generation=12}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_d,generation=13}
   [junit4]   2> 540167 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
   [junit4]   2> 540170 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@281df502 main
   [junit4]   2> 540171 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540171 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540172 T1141 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_d,generation=13}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_e,generation=14}
   [junit4]   2> 540172 T1141 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
   [junit4]   2> 540172 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@281df502 main{StandardDirectoryReader(segments_d:72:nrt _l(4.7):C1)}
   [junit4]   2> 540173 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@8d0aee58 main
   [junit4]   2> 540173 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540173 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540174 T1143 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 540175 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8d0aee58 main{StandardDirectoryReader(segments_d:72:nrt _l(4.7):C1)}
   [junit4]   2> 540177 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@c9b94853 main
   [junit4]   2> 540178 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540178 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540179 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c9b94853 main{StandardDirectoryReader(segments_e:75:nrt _l(4.7):C1 _m(4.7):C1)}
   [junit4]   2> 540181 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_e,generation=14}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_f,generation=15}
   [junit4]   2> 540182 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
   [junit4]   2> 540184 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@6e5c9fa6 main
   [junit4]   2> 540184 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540185 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540185 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e5c9fa6 main{StandardDirectoryReader(segments_f:82:nrt _n(4.7):C1 _o(4.7):C1)}
   [junit4]   2> 540185 T1141 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_f,generation=15}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_g,generation=16}
   [junit4]   2> 540186 T1141 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
   [junit4]   2> 540187 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@de00ecb main
   [junit4]   2> 540187 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540188 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540188 T1143 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 540188 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@de00ecb main{StandardDirectoryReader(segments_f:82:nrt _n(4.7):C1 _o(4.7):C1)}
   [junit4]   2> 540191 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@17381fb3 main
   [junit4]   2> 540191 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540192 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540192 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17381fb3 main{StandardDirectoryReader(segments_g:87:nrt _o(4.7):C1 _p(4.7):C1)}
   [junit4]   2> 540245 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_g,generation=16}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_h,generation=17}
   [junit4]   2> 540245 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
   [junit4]   2> 540249 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@4657da17 main
   [junit4]   2> 540249 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540249 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540251 T1141 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_h,generation=17}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_i,generation=18}
   [junit4]   2> 540251 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4657da17 main{StandardDirectoryReader(segments_h:94:nrt _r(4.7):C1)}
   [junit4]   2> 540251 T1141 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
   [junit4]   2> 540252 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@8b5f02d0 main
   [junit4]   2> 540252 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540253 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540253 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8b5f02d0 main{StandardDirectoryReader(segments_h:94:nrt _r(4.7):C1)}
   [junit4]   2> 540255 T1143 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_i,generation=18}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_j,generation=19}
   [junit4]   2> 540256 T1143 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
   [junit4]   2> 540258 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@52d7c86 main
   [junit4]   2> 540258 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540259 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540259 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_j,generation=19}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_k,generation=20}
   [junit4]   2> 540260 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
   [junit4]   2> 540259 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52d7c86 main{StandardDirectoryReader(segments_j:100:nrt _s(4.7):C1 _t(4.7):C1)}
   [junit4]   2> 540260 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@6c56ecda main
   [junit4]   2> 540261 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540261 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540262 T1141 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 540262 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6c56ecda main{StandardDirectoryReader(segments_j:100:nrt _s(4.7):C1 _t(4.7):C1)}
   [junit4]   2> 540265 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@68ccda9e main
   [junit4]   2> 540265 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540265 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540266 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@68ccda9e main{StandardDirectoryReader(segments_k:105:nrt _s(4.7):C1 _u(4.7):C1)}
   [junit4]   2> 540268 T1143 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_k,generation=20}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_l,generation=21}
   [junit4]   2> 540268 T1143 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
   [junit4]   2> 540271 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@c609404b main
   [junit4]   2> 540271 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540271 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540272 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_l,generation=21}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_m,generation=22}
   [junit4]   2> 540273 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
   [junit4]   2> 540272 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c609404b main{StandardDirectoryReader(segments_l:112:nrt _s(4.7):C1 _w(4.7):C1)}
   [junit4]   2> 540274 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@8edc46be main
   [junit4]   2> 540274 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540275 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540275 T1141 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 540276 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8edc46be main{StandardDirectoryReader(segments_l:112:nrt _s(4.7):C1 _w(4.7):C1)}
   [junit4]   2> 540278 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@ca5f86e7 main
   [junit4]   2> 540279 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540279 T1143 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540280 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ca5f86e7 main{StandardDirectoryReader(segments_m:117:nrt _w(4.7):C1 _x(4.7):C1)}
   [junit4]   2> 540282 T1143 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_m,generation=22}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_n,generation=23}
   [junit4]   2> 540283 T1143 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
   [junit4]   2> 540283 T1143 oass.SolrIndexSearcher.<init> Opening Searcher@4fbd8a95 main
   [junit4]   2> 540283 T1143 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540284 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540285 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4fbd8a95 main{StandardDirectoryReader(segments_n:120:nrt _x(4.7):C1 _y(4.7):C1)}
   [junit4]   2> 540286 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_n,generation=23}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_o,generation=24}
   [junit4]   2> 540287 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
   [junit4]   2> 540287 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@e33ee866 main
   [junit4]   2> 540287 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540288 T1141 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540288 T1141 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 540288 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e33ee866 main{StandardDirectoryReader(segments_o:124:nrt _y(4.7):C1 _z(4.7):C1)}
   [junit4]   2> 540289 T1141 oass.SolrIndexSearcher.<init> Opening Searcher@5c780fa1 main
   [junit4]   2> 540289 T1141 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540290 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5c780fa1 main{StandardDirectoryReader(segments_o:124:nrt _y(4.7):C1 _z(4.7):C1)}
   [junit4]   2> 540291 T1142 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540294 T1142 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_o,generation=24}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_p,generation=25}
   [junit4]   2> 540294 T1142 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
   [junit4]   2> 540295 T1142 oass.SolrIndexSearcher.<init> Opening Searcher@f3e274e9 main
   [junit4]   2> 540295 T1142 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540296 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f3e274e9 main{StandardDirectoryReader(segments_p:128:nrt _y(4.7):C1 _10(4.7):C1)}
   [junit4]   2> 540301 T1130 C844 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540301 T1130 C844 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 540302 T1130 C844 oass.SolrIndexSearcher.<init> Opening Searcher@57a89a72 main
   [junit4]   2> 540303 T1130 C844 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540304 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57a89a72 main{StandardDirectoryReader(segments_p:128:nrt _y(4.7):C1 _10(4.7):C1)}
   [junit4]   2> 540304 T1130 C844 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 3
   [junit4]   2> 540305 T1130 oas.SolrTestCaseJ4.tearDown ###Ending testMultiThreaded
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testMultiThreaded -Dtests.seed=2F3D237ED0942F2D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=gu_IN -Dtests.timezone=Africa/Kampala -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 0.30s J1 | SignatureUpdateProcessorFactoryTest.testMultiThreaded <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<1> but was:<2>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([2F3D237ED0942F2D:4EA8F99E384525DB]: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:780)
   [junit4]   2> 540312 T1130 oas.SolrTestCaseJ4.setUp ###Starting testDupeDetection
   [junit4]   2> ASYNC  NEW_CORE C845 name=collection1 org.apache.solr.core.SolrCore@760957fd
   [junit4]   2> 540323 T1130 C845 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 540323 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 540331 T1130 C845 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540331 T1130 C845 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_p,generation=25}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_q,generation=26}
   [junit4]   2> 540332 T1130 C845 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
   [junit4]   2> 540333 T1130 C845 oass.SolrIndexSearcher.<init> Opening Searcher@c6ef81eb main
   [junit4]   2> 540333 T1130 C845 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540334 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c6ef81eb main{StandardDirectoryReader(segments_q:129:nrt)}
   [junit4]   2> 540335 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 540339 T1130 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540341 T1130 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_q,generation=26}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_r,generation=27}
   [junit4]   2> 540342 T1130 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
   [junit4]   2> 540342 T1130 oass.SolrIndexSearcher.<init> Opening Searcher@dfaf8e5c main
   [junit4]   2> 540343 T1130 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540344 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dfaf8e5c main{StandardDirectoryReader(segments_r:131:nrt _11(4.7):C2)}
   [junit4]   2> 540346 T1130 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540349 T1130 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_r,generation=27}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_s,generation=28}
   [junit4]   2> 540349 T1130 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
   [junit4]   2> 540350 T1130 oass.SolrIndexSearcher.<init> Opening Searcher@1f41eb8c main
   [junit4]   2> 540351 T1130 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540352 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f41eb8c main{StandardDirectoryReader(segments_s:134:nrt _11(4.7):C2/1:delGen=1 _12(4.7):C1)}
   [junit4]   2> 540352 T1130 oas.SolrTestCaseJ4.tearDown ###Ending testDupeDetection
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testDupeDetection -Dtests.seed=2F3D237ED0942F2D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=gu_IN -Dtests.timezone=Africa/Kampala -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 0.04s J1 | SignatureUpdateProcessorFactoryTest.testDupeDetection <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<1> but was:<2>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([2F3D237ED0942F2D:740DD8B6D3D38C51]: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> 540358 T1130 oas.SolrTestCaseJ4.setUp ###Starting testDupeAllFieldsDetection
   [junit4]   2> 540362 T1130 C845 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 540363 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 540368 T1130 C845 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540369 T1130 C845 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_s,generation=28}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_t,generation=29}
   [junit4]   2> 540369 T1130 C845 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
   [junit4]   2> 540370 T1130 C845 oass.SolrIndexSearcher.<init> Opening Searcher@c4b40e53 main
   [junit4]   2> 540371 T1130 C845 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540372 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c4b40e53 main{StandardDirectoryReader(segments_t:135:nrt)}
   [junit4]   2> 540372 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 540378 T1130 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540379 T1130 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_t,generation=29}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_u,generation=30}
   [junit4]   2> 540380 T1130 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 30
   [junit4]   2> 540381 T1130 oass.SolrIndexSearcher.<init> Opening Searcher@a80f9708 main
   [junit4]   2> 540381 T1130 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540382 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a80f9708 main{StandardDirectoryReader(segments_u:137:nrt _13(4.7):C3)}
   [junit4]   2> 540383 T1130 oas.SolrTestCaseJ4.tearDown ###Ending testDupeAllFieldsDetection
   [junit4]   2> 540387 T1130 oas.SolrTestCaseJ4.setUp ###Starting testNonStringFieldsValues
   [junit4]   2> 540392 T1130 C845 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 540393 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 540399 T1130 C845 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540400 T1130 C845 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_u,generation=30}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_v,generation=31}
   [junit4]   2> 540400 T1130 C845 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 31
   [junit4]   2> 540401 T1130 C845 oass.SolrIndexSearcher.<init> Opening Searcher@2ed30215 main
   [junit4]   2> 540402 T1130 C845 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540403 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2ed30215 main{StandardDirectoryReader(segments_v:138:nrt)}
   [junit4]   2> 540403 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 540408 T1130 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 540411 T1130 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540413 T1130 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_v,generation=31}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_w,generation=32}
   [junit4]   2> 540414 T1130 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 32
   [junit4]   2> 540415 T1130 oass.SolrIndexSearcher.<init> Opening Searcher@221628ae main
   [junit4]   2> 540415 T1130 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540416 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@221628ae main{StandardDirectoryReader(segments_w:140:nrt _14(4.7):C5/1:delGen=1)}
   [junit4]   2> 540417 T1130 oas.SolrTestCaseJ4.tearDown ###Ending testNonStringFieldsValues
   [junit4]   2> 540421 T1130 oas.SolrTestCaseJ4.setUp ###Starting testNonIndexedSignatureField
   [junit4]   2> 540426 T1130 C845 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 540427 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 540433 T1130 C845 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540434 T1130 C845 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_w,generation=32}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_x,generation=33}
   [junit4]   2> 540434 T1130 C845 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 33
   [junit4]   2> 540435 T1130 C845 oass.SolrIndexSearcher.<init> Opening Searcher@6668323c main
   [junit4]   2> 540436 T1130 C845 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540437 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6668323c main{StandardDirectoryReader(segments_x:141:nrt)}
   [junit4]   2> 540438 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
   [junit4]   2> 540443 T1130 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540444 T1130 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_x,generation=33}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_y,generation=34}
   [junit4]   2> 540445 T1130 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 34
   [junit4]   2> 540446 T1130 oass.SolrIndexSearcher.<init> Opening Searcher@ee74b515 main
   [junit4]   2> 540446 T1130 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540447 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ee74b515 main{StandardDirectoryReader(segments_y:143:nrt _15(4.7):C2)}
   [junit4]   2> 540448 T1130 oas.SolrTestCaseJ4.tearDown ###Ending testNonIndexedSignatureField
   [junit4]   2> 540452 T1130 oas.SolrTestCaseJ4.setUp ###Starting testFailNonIndexedSigWithOverwriteDupes
   [junit4]   2> 540457 T1130 C845 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 540458 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
   [junit4]   2> 540464 T1130 C845 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 540465 T1130 C845 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_y,generation=34}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8a7ad363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e6c5c2)),segFN=segments_z,generation=35}
   [junit4]   2> 540465 T1130 C845 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 35
   [junit4]   2> 540466 T1130 C845 oass.SolrIndexSearcher.<init> Opening Searcher@91a04f6c main
   [junit4]   2> 540466 T1130 C845 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 540467 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@91a04f6c main{StandardDirectoryReader(segments_z:144:nrt)}
   [junit4]   2> 540468 T1130 C845 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
   [junit4]   2> 540469 T1130 oas.SolrTestCaseJ4.tearDown ###Ending testFailNonIndexedSigWithOverwriteDupes
   [junit4]   2> 540471 T1130 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 540471 T1130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=95337965
   [junit4]   2> 540472 T1130 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@760957fd
   [junit4]   2> 540481 T1130 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> 540481 T1130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 540482 T1130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 540482 T1130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 540482 T1130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 540483 T1130 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 540483 T1130 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1389102310406 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1389102310406;done=false>>]
   [junit4]   2> 540483 T1130 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1389102310406
   [junit4]   2> 540484 T1130 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1389102310406/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1389102310406/index;done=false>>]
   [junit4]   2> 540484 T1130 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1389102310406/index
   [junit4]   2> NOTE: test params are: codec=Lucene46: {ints_is=PostingsFormat(name=Direct), name=PostingsFormat(name=Asserting), weight=PostingsFormat(name=Memory doPackFST= false), signatureField=MockFixedIntBlock(blockSize=370), v_t=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=gu_IN, timezone=Africa/Kampala
   [junit4]   2> NOTE: Linux 3.8.0-35-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=82081568,total=171311104
   [junit4]   2> NOTE: All tests run in this JVM: [TestBinaryField, TestWordDelimiterFilterFactory, RAMDirectoryFactoryTest, ConnectionManagerTest, HardAutoCommitTest, TestShardHandlerFactory, TestAnalyzeInfixSuggestions, SolrRequestParserTest, TestSolrJ, TestIBSimilarityFactory, QueryResultKeyTest, TestFuzzyAnalyzedSuggestions, TestDynamicFieldCollectionResource, TestFoldingMultitermQuery, HdfsDirectoryTest, TestFastLRUCache, TestReloadAndDeleteDocs, RequiredFieldsTest, TestAtomicUpdateErrorCases, UniqFieldsUpdateProcessorFactoryTest, TestRangeQuery, TestSweetSpotSimilarityFactory, TestFiltering, TestSolrCoreProperties, SpellCheckCollatorTest, SortByFunctionTest, BasicDistributedZk2Test, TimeZoneUtilsTest, TermVectorComponentDistributedTest, BadCopyFieldTest, SpellCheckComponentTest, ConvertedLegacyTest, TestDFRSimilarityFactory, NumericFieldsTest, TestCloudManagedSchemaAddField, SolrPluginUtilsTest, OutputWriterTest, XmlUpdateRequestHandlerTest, TestXIncludeConfig, TestFastWriter, CoreContainerCoreInitFailuresTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestQuerySenderListener, ShardRoutingCustomTest, CachingDirectoryFactoryTest, JSONWriterTest, TestDistributedGrouping, RequestHandlersTest, AlternateDirectoryTest, OpenCloseCoreStressTest, TriLevelCompositeIdRoutingTest, TestDefaultSimilarityFactory, LeaderElectionIntegrationTest, TestQueryTypes, FileUtilsTest, StandardRequestHandlerTest, NoCacheHeaderTest, TestIndexSearcher, TestDefaultSearchFieldResource, PluginInfoTest, CurrencyFieldXmlFileTest, TestCustomSort, DebugComponentTest, CurrencyFieldOpenExchangeTest, TestUpdate, TestCollationKeyRangeQueries, TestRecovery, ChaosMonkeySafeLeaderTest, DeleteShardTest, HdfsUnloadDistributedZkTest, TestOmitPositions, TestSolrDeletionPolicy2, TestSolrXml, DistributedDebugComponentTest, TestStressReorder, TestPartialUpdateDeduplication, TestCodecSupport, DocValuesMultiTest, DirectSolrSpellCheckerTest, TestCollationField, PathHierarchyTokenizerFactoryTest, TestStressRecovery, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, TestHashPartitioner, TestSolr4Spatial, TestGroupingSearch, OverseerCollectionProcessorTest, ShowFileRequestHandlerTest, SignatureUpdateProcessorFactoryTest]
   [junit4] Completed on J1 in 1.57s, 6 tests, 2 failures <<< FAILURES!

[...truncated 427 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:1307: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:940: There were test failures: 348 suites, 1522 tests, 2 failures, 66 ignored (6 assumptions)

Total time: 36 minutes 7 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