Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/2186/

1 tests failed.
REGRESSION:  
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds

Error Message:
1: soft wasn't fast enough

Stack Trace:
java.lang.AssertionError: 1: soft wasn't fast enough
        at 
__randomizedtesting.SeedInfo.seed([BCADA386AC22601D:E0B80DBF47A02165]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at 
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:316)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
        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.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:65)
        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:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
        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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        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:365)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10759 lines...]
   [junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
   [junit4]   2> log4j:WARN No such property [conversionPattern] in 
org.apache.solr.util.SolrLogLayout.
   [junit4]   2> Creating dataDir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001
   [junit4]   2> 4773 T11 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(true) and clientAuth (true)
   [junit4]   2> 4936 T11 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 5115 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader 
for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 5124 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 5125 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 5126 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 6788 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
5.0.0
   [junit4]   2> 7366 T11 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 7437 T11 oass.IndexSchema.readSchema Reading Solr Schema from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 7574 T11 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 10704 T11 oass.OpenExchangeRatesOrgProvider.init Initialized 
with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 10791 T11 oass.IndexSchema.readSchema default search field in 
schema is text
   [junit4]   2> 10795 T11 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 12187 T11 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 12223 T11 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 12230 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 12261 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 12262 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 12263 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 12265 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 12265 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 12289 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 12289 T11 oasc.SolrResourceLoader.locateSolrHome using system 
property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr
   [junit4]   2> 12290 T11 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/'
   [junit4]   2> 12636 T11 oasc.CoreContainer.<init> New CoreContainer 
1756174045
   [junit4]   2> 12636 T11 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/]
   [junit4]   2> 12809 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
socketTimeout to: 0
   [junit4]   2> 12809 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
urlScheme to: null
   [junit4]   2> 12810 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
connTimeout to: 0
   [junit4]   2> 12811 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
maxConnectionsPerHost to: 20
   [junit4]   2> 12811 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
maxConnections to: 10000
   [junit4]   2> 12812 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
corePoolSize to: 0
   [junit4]   2> 12813 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
maximumPoolSize to: 2147483647
   [junit4]   2> 12813 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
maxThreadIdleTime to: 5
   [junit4]   2> 12814 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
sizeOfQueue to: -1
   [junit4]   2> 12814 T11 oashc.HttpShardHandlerFactory.getParameter Setting 
fairnessPolicy to: false
   [junit4]   2> 14193 T11 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=false
   [junit4]   2> 14232 T11 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 14242 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 14258 T11 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 15187 T12 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 15191 T12 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 15193 T12 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 15194 T12 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 15471 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
5.0.0
   [junit4]   2> 15607 T12 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 15608 T12 oass.IndexSchema.readSchema Reading Solr Schema from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 15683 T12 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 16252 T12 oass.OpenExchangeRatesOrgProvider.init Initialized 
with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 16272 T12 oass.IndexSchema.readSchema default search field in 
schema is text
   [junit4]   2> 16275 T12 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 16305 T12 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 16311 T12 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 16316 T12 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 16318 T12 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 16318 T12 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 16319 T12 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 16320 T12 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 16321 T12 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 16328 T12 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 16608 T12 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 16634 T12 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/
   [junit4]   2> 16641 T12 oasc.JmxMonitoredMap.<init> No JMX servers found, 
not exposing Solr information with JMX.
   [junit4]   2> 16811 T12 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001
   [junit4]   2> 16812 T12 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index/
   [junit4]   2> 16813 T12 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 16835 T12 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index
   [junit4]   2> 16839 T12 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.6702968089218011]
   [junit4]   2> 17144 T12 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_1,generation=1}
   [junit4]   2> 17151 T12 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 17272 T12 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 19751 T12 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 19959 T12 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 19960 T12 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 19961 T12 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 19961 T12 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 19962 T12 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 19962 T12 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 20016 T12 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 20017 T12 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 20322 T12 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 20322 T12 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 20323 T12 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 20391 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 20392 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 20392 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 20393 T12 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 20401 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/replication: solr.ReplicationHandler
   [junit4]   2> 20476 T12 oasc.RequestHandlers.initHandlersFromConfig created 
standard: solr.StandardRequestHandler
   [junit4]   2> 20537 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/get: solr.RealTimeGetHandler
   [junit4]   2> 20732 T12 oasc.RequestHandlers.initHandlersFromConfig created 
dismax: solr.SearchHandler
   [junit4]   2> 20734 T12 oasc.RequestHandlers.initHandlersFromConfig created 
mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 20745 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 20834 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 20915 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 20915 T12 oasc.RequestHandlers.initHandlersFromConfig created 
defaults: solr.StandardRequestHandler
   [junit4]   2> 20916 T12 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 20916 T12 oasc.RequestHandlers.initHandlersFromConfig created 
lazy: solr.StandardRequestHandler
   [junit4]   2> 20964 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/update: solr.UpdateRequestHandler
   [junit4]   2> 20964 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 20965 T12 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 20965 T12 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 20966 T12 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 20967 T12 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 20967 T12 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 20968 T12 oasc.RequestHandlers.initHandlersFromConfig created 
mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 20968 T12 oasc.RequestHandlers.initHandlersFromConfig created 
tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 21025 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/mlt: solr.MoreLikeThisHandler
   [junit4]   2> 21081 T12 oasc.RequestHandlers.initHandlersFromConfig created 
/debug/dump: solr.DumpRequestHandler
   [junit4]   2> 21361 T12 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21410 T12 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21412 T12 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21415 T12 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 21425 T12 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 21598 T12 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 21599 T12 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 21600 T12 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 21618 T12 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_1,generation=1}
   [junit4]   2> 21619 T12 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 21881 T12 oass.SolrIndexSearcher.<init> Opening 
Searcher@5cbe0cd7[collection1] main
   [junit4]   2> 21985 T12 oasr.RestManager.init Initializing RestManager with 
initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 22173 T12 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 22174 T12 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 22188 T12 oasr.ManagedResourceStorage$JsonStorage.store Saved 
JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 22188 T12 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 22189 T12 oashc.SpellCheckComponent.inform Initializing spell 
checkers
   [junit4]   2> 22565 T12 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 23277 T12 oasc.CoreContainer.registerCore registering core: 
collection1
   [junit4]   2> 23279 T13 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5cbe0cd7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 23330 T11 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 23407 T11 oas.SolrTestCaseJ4.setUp ###Starting 
testSoftAndHardCommitMaxTimeRapidAdds
   [junit4]   2> 23408 T11 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 23410 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 23411 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 23412 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 23673 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
5.0.0
   [junit4]   2> 23761 T11 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 23763 T11 oass.IndexSchema.readSchema Reading Solr Schema from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 23835 T11 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 24293 T11 oass.OpenExchangeRatesOrgProvider.init Initialized 
with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 24315 T11 oass.IndexSchema.readSchema default search field in 
schema is text
   [junit4]   2> 24318 T11 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 24345 T11 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 24351 T11 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 24357 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 24359 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 24360 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 24360 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 24361 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 24362 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 24362 T11 oasc.CoreContainer.reload Reloading SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 24363 T11 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/
   [junit4]   2> 24364 T11 oasc.JmxMonitoredMap.<init> No JMX servers found, 
not exposing Solr information with JMX.
   [junit4]   2> 24364 T11 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index/
   [junit4]   2> 24365 T11 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 24367 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 24368 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 24368 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 24369 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 24370 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 24370 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 24371 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 24371 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 24372 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 24373 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 24373 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 24374 T11 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 24375 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 24375 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 24376 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 24377 T11 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 24377 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/replication: solr.ReplicationHandler
   [junit4]   2> 24378 T11 oasc.RequestHandlers.initHandlersFromConfig created 
standard: solr.StandardRequestHandler
   [junit4]   2> 24378 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/get: solr.RealTimeGetHandler
   [junit4]   2> 24379 T11 oasc.RequestHandlers.initHandlersFromConfig created 
dismax: solr.SearchHandler
   [junit4]   2> 24379 T11 oasc.RequestHandlers.initHandlersFromConfig created 
mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 24380 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 24381 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 24381 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 24382 T11 oasc.RequestHandlers.initHandlersFromConfig created 
defaults: solr.StandardRequestHandler
   [junit4]   2> 24382 T11 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 24383 T11 oasc.RequestHandlers.initHandlersFromConfig created 
lazy: solr.StandardRequestHandler
   [junit4]   2> 24384 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update: solr.UpdateRequestHandler
   [junit4]   2> 24384 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 24385 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 24385 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 24386 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 24386 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 24388 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 24388 T11 oasc.RequestHandlers.initHandlersFromConfig created 
mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 24389 T11 oasc.RequestHandlers.initHandlersFromConfig created 
tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 24389 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/mlt: solr.MoreLikeThisHandler
   [junit4]   2> 24390 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/debug/dump: solr.DumpRequestHandler
   [junit4]   2> 24419 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 24422 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 24425 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 24427 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 24429 T11 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 24439 T11 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 24440 T11 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 24441 T11 oass.SolrIndexSearcher.<init> Opening 
Searcher@1cd52551[collection1] main
   [junit4]   2> 24442 T11 oasr.RestManager.init Initializing RestManager with 
initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 24442 T11 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 24443 T11 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 24443 T11 oasr.ManagedResourceStorage$JsonStorage.store Saved 
JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 24444 T11 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 24444 T11 oashc.SpellCheckComponent.inform Initializing spell 
checkers
   [junit4]   2> 24448 T11 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 24469 T15 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1cd52551[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 24469 T11 oasu.DefaultSolrCoreState.newIndexWriter Creating 
new IndexWriter...
   [junit4]   2> 24470 T11 oasu.DefaultSolrCoreState.newIndexWriter Waiting 
until IndexWriter is unused... core=collection1
   [junit4]   2> 24471 T11 oasu.DefaultSolrCoreState.newIndexWriter Closing old 
IndexWriter... core=collection1
   [junit4]   2> 24473 T11 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.AlcoholicMergePolicy: 
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1744949929, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 24475 T11 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_1,generation=1}
   [junit4]   2> 24475 T11 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 24476 T11 oasu.DefaultSolrCoreState.newIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 24476 T11 oass.SolrIndexSearcher.<init> Opening 
Searcher@1fcc932b[collection1] main
   [junit4]   2> 24485 T11 oasc.CoreContainer.registerCore replacing core: 
collection1
   [junit4]   2> 24485 T11 oasc.SolrCore.close [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1d2d2db8
   [junit4]   2> 24486 T11 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=0,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 24487 T11 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> 24508 T15 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1fcc932b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> ASYNC  NEW_CORE C0 name=collection1 
org.apache.solr.core.SolrCore@a06b64e
   [junit4]   2> 25692 T11 C0 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[5000 (1483135354418495488)]} 0 1187
   [junit4]   2> 25764 T11 C0 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[5001 (1483135355085389824)]} 0 1
   [junit4]   2> 25769 T11 C0 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[5002 (1483135355090632704)]} 0 2
   [junit4]   2> 25773 T11 C0 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[5003 (1483135355094827008)]} 0 2
   [junit4]   2> 25777 T11 C0 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[5004 (1483135355099021312)]} 0 2
   [junit4]   2> 26182 T17 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 26780 T11 oas.SolrTestCaseJ4.tearDown ###Ending 
testSoftAndHardCommitMaxTimeRapidAdds
   [junit4]   2> 26882 T16 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SoftAutoCommitTest 
-Dtests.method=testSoftAndHardCommitMaxTimeRapidAdds 
-Dtests.seed=BCADA386AC22601D -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=ga_IE -Dtests.timezone=Australia/Currie -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 3.57s J3 | 
SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: 1: soft wasn't fast 
enough
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([BCADA386AC22601D:E0B80DBF47A02165]:0)
   [junit4]    >        at 
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:316)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 26921 T11 oas.SolrTestCaseJ4.setUp ###Starting 
testSoftAndHardCommitMaxTimeDelete
   [junit4]   2> 26922 T11 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 26924 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 26924 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 26925 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 27164 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
5.0.0
   [junit4]   2> 27272 T11 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 27274 T11 oass.IndexSchema.readSchema Reading Solr Schema from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 27345 T17 oass.SolrIndexSearcher.<init> Opening 
Searcher@7bff0922[collection1] main
   [junit4]   2> 27354 T17 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 27354 T15 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7bff0922[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)))}
   [junit4]   2> 27356 T16 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_2,generation=2}
   [junit4]   2> 27357 T16 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 2
   [junit4]   2> 27359 T16 oasc.SolrCore.openNewSearcher SolrIndexSearcher has 
not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 27361 T16 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 27410 T11 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 28017 T11 oass.OpenExchangeRatesOrgProvider.init Initialized 
with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 28036 T11 oass.IndexSchema.readSchema default search field in 
schema is text
   [junit4]   2> 28039 T11 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 28066 T11 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 28073 T11 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 28079 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 28080 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 28081 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 28082 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 28082 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 28083 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 28083 T11 oasc.CoreContainer.reload Reloading SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 28084 T11 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/
   [junit4]   2> 28085 T11 oasc.JmxMonitoredMap.<init> No JMX servers found, 
not exposing Solr information with JMX.
   [junit4]   2> 28085 T11 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index/
   [junit4]   2> 28086 T11 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 28088 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 28089 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 28089 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 28090 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 28090 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 28091 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 28092 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 28093 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 28093 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 28094 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 28095 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 28095 T11 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 28096 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 28097 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 28097 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 28098 T11 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 28098 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/replication: solr.ReplicationHandler
   [junit4]   2> 28099 T11 oasc.RequestHandlers.initHandlersFromConfig created 
standard: solr.StandardRequestHandler
   [junit4]   2> 28099 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/get: solr.RealTimeGetHandler
   [junit4]   2> 28100 T11 oasc.RequestHandlers.initHandlersFromConfig created 
dismax: solr.SearchHandler
   [junit4]   2> 28100 T11 oasc.RequestHandlers.initHandlersFromConfig created 
mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 28101 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 28102 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 28102 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 28103 T11 oasc.RequestHandlers.initHandlersFromConfig created 
defaults: solr.StandardRequestHandler
   [junit4]   2> 28103 T11 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 28104 T11 oasc.RequestHandlers.initHandlersFromConfig created 
lazy: solr.StandardRequestHandler
   [junit4]   2> 28104 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update: solr.UpdateRequestHandler
   [junit4]   2> 28105 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 28105 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 28106 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 28106 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 28107 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 28107 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 28108 T11 oasc.RequestHandlers.initHandlersFromConfig created 
mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 28109 T11 oasc.RequestHandlers.initHandlersFromConfig created 
tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 28109 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/mlt: solr.MoreLikeThisHandler
   [junit4]   2> 28110 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/debug/dump: solr.DumpRequestHandler
   [junit4]   2> 28128 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 28131 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 28133 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 28135 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 28138 T11 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 28141 T11 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 28141 T11 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 28143 T11 oass.SolrIndexSearcher.<init> Opening 
Searcher@10cb09c8[collection1] main
   [junit4]   2> 28144 T11 oasr.RestManager.init Initializing RestManager with 
initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 28144 T11 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 28145 T11 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 28145 T11 oasr.ManagedResourceStorage$JsonStorage.store Saved 
JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 28146 T11 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 28146 T11 oashc.SpellCheckComponent.inform Initializing spell 
checkers
   [junit4]   2> 28150 T11 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 28168 T11 oasu.DefaultSolrCoreState.newIndexWriter Creating 
new IndexWriter...
   [junit4]   2> 28168 T11 oasu.DefaultSolrCoreState.newIndexWriter Waiting 
until IndexWriter is unused... core=collection1
   [junit4]   2> 28169 T11 oasu.DefaultSolrCoreState.newIndexWriter Closing old 
IndexWriter... core=collection1
   [junit4]   2> 28168 T18 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@10cb09c8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)))}
   [junit4]   2> 28170 T11 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1668382537723347]
   [junit4]   2> 28173 T11 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_2,generation=2}
   [junit4]   2> 28174 T11 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 2
   [junit4]   2> 28174 T11 oasu.DefaultSolrCoreState.newIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 28179 T11 oass.SolrIndexSearcher.<init> Opening 
Searcher@15d61781[collection1] main
   [junit4]   2> 28180 T11 oasc.CoreContainer.registerCore replacing core: 
collection1
   [junit4]   2> 28180 T18 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@15d61781[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)))}
   [junit4]   2> 28180 T11 oasc.SolrCore.close [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@a06b64e
   [junit4]   2> 28182 T11 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=2,autocommit maxTime=1200ms,autocommits=1,soft 
autocommit maxTime=500ms,soft 
autocommits=1,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=339,transaction_logs_total_number=1}
   [junit4]   2> 28183 T11 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> ASYNC  NEW_CORE C1 name=collection1 
org.apache.solr.core.SolrCore@5fb2943c
   [junit4]   2> 28189 T11 C1 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[529 (1483135357625040896)]} 0 5
   [junit4]   2> 28192 T11 C1 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 28308 T11 C1 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_3,generation=3}
   [junit4]   2> 28309 T11 C1 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 28319 T11 C1 oass.SolrIndexSearcher.<init> Opening 
Searcher@6cd9f6cb[collection1] main
   [junit4]   2> 28321 T11 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 28321 T18 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6cd9f6cb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)
 Uninverting(_1(5.0.0):c1)))}
   [junit4]   2> 28322 T11 C1 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {commit=} 0 130
   [junit4]   2> 28331 T11 C1 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {delete=[529 (-1483135357776035840)]} 0 2
   [junit4]   2> 28832 T20 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 28865 T20 oass.SolrIndexSearcher.<init> Opening 
Searcher@22c57d32[collection1] main
   [junit4]   2> 28867 T20 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 28868 T18 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@22c57d32[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)))}
   [junit4]   2> 28871 T11 C1 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[550 (1483135358341218304)]} 0 4
   [junit4]   2> 29372 T20 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 29418 T20 oass.SolrIndexSearcher.<init> Opening 
Searcher@46db97c0[collection1] main
   [junit4]   2> 29419 T20 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 29419 T18 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@46db97c0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)
 Uninverting(_2(5.0.0):c1)))}
   [junit4]   2> 29532 T19 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 29533 T19 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_4,generation=4}
   [junit4]   2> 29534 T19 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 4
   [junit4]   2> 29535 T19 oasc.SolrCore.openNewSearcher SolrIndexSearcher has 
not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 29535 T19 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 31535 T11 oas.SolrTestCaseJ4.tearDown ###Ending 
testSoftAndHardCommitMaxTimeDelete
   [junit4]   2> 31548 T11 oas.SolrTestCaseJ4.setUp ###Starting 
testSoftAndHardCommitMaxTimeMixedAdds
   [junit4]   2> 31548 T11 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 31550 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 31550 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 31551 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 31699 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
5.0.0
   [junit4]   2> 31766 T11 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 31768 T11 oass.IndexSchema.readSchema Reading Solr Schema from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 31808 T11 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 32199 T11 oass.OpenExchangeRatesOrgProvider.init Initialized 
with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 32219 T11 oass.IndexSchema.readSchema default search field in 
schema is text
   [junit4]   2> 32222 T11 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 32247 T11 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 32253 T11 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 32258 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 32259 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 32260 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 32260 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading 
exchange rates from open-exchange-rates.json
   [junit4]   2> 32261 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 32262 T11 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 32262 T11 oasc.CoreContainer.reload Reloading SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 32263 T11 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/
   [junit4]   2> 32263 T11 oasc.JmxMonitoredMap.<init> No JMX servers found, 
not exposing Solr information with JMX.
   [junit4]   2> 32264 T11 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index/
   [junit4]   2> 32264 T11 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 32266 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 32267 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 32267 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 32268 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 32268 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 32269 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 32269 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 32270 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 32270 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 32271 T11 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 32272 T11 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 32272 T11 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 32273 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 32273 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 32274 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 32274 T11 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 32275 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/replication: solr.ReplicationHandler
   [junit4]   2> 32275 T11 oasc.RequestHandlers.initHandlersFromConfig created 
standard: solr.StandardRequestHandler
   [junit4]   2> 32276 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/get: solr.RealTimeGetHandler
   [junit4]   2> 32276 T11 oasc.RequestHandlers.initHandlersFromConfig created 
dismax: solr.SearchHandler
   [junit4]   2> 32277 T11 oasc.RequestHandlers.initHandlersFromConfig created 
mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 32277 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 32278 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 32279 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 32279 T11 oasc.RequestHandlers.initHandlersFromConfig created 
defaults: solr.StandardRequestHandler
   [junit4]   2> 32279 T11 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 32280 T11 oasc.RequestHandlers.initHandlersFromConfig created 
lazy: solr.StandardRequestHandler
   [junit4]   2> 32281 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/update: solr.UpdateRequestHandler
   [junit4]   2> 32281 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 32282 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 32282 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 32283 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 32283 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 32284 T11 oasc.RequestHandlers.initHandlersFromConfig created 
spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 32284 T11 oasc.RequestHandlers.initHandlersFromConfig created 
mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 32285 T11 oasc.RequestHandlers.initHandlersFromConfig created 
tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 32285 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/mlt: solr.MoreLikeThisHandler
   [junit4]   2> 32286 T11 oasc.RequestHandlers.initHandlersFromConfig created 
/debug/dump: solr.DumpRequestHandler
   [junit4]   2> 32310 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 32312 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 32315 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 32317 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 32324 T11 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 32327 T11 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 32327 T11 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 32328 T11 oass.SolrIndexSearcher.<init> Opening 
Searcher@2f51e4c5[collection1] main
   [junit4]   2> 32329 T11 oasr.RestManager.init Initializing RestManager with 
initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 32330 T11 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 32330 T11 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 32331 T11 oasr.ManagedResourceStorage$JsonStorage.store Saved 
JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 32331 T11 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 32331 T11 oashc.SpellCheckComponent.inform Initializing spell 
checkers
   [junit4]   2> 32336 T11 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 32355 T11 oasu.DefaultSolrCoreState.newIndexWriter Creating 
new IndexWriter...
   [junit4]   2> 32355 T21 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@2f51e4c5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)
 Uninverting(_2(5.0.0):c1)))}
   [junit4]   2> 32356 T11 oasu.DefaultSolrCoreState.newIndexWriter Waiting 
until IndexWriter is unused... core=collection1
   [junit4]   2> 32357 T11 oasu.DefaultSolrCoreState.newIndexWriter Closing old 
IndexWriter... core=collection1
   [junit4]   2> 32358 T11 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=11, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=12.625, 
floorSegmentMB=0.314453125, forceMergeDeletesPctAllowed=26.668270760127427, 
segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7998654429300673
   [junit4]   2> 32363 T11 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_4,generation=4}
   [junit4]   2> 32363 T11 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 4
   [junit4]   2> 32364 T11 oasu.DefaultSolrCoreState.newIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 32371 T11 oass.SolrIndexSearcher.<init> Opening 
Searcher@2bf7a4f8[collection1] main
   [junit4]   2> 32372 T11 oasc.CoreContainer.registerCore replacing core: 
collection1
   [junit4]   2> 32372 T21 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@2bf7a4f8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)
 Uninverting(_2(5.0.0):c1)))}
   [junit4]   2> 32373 T11 oasc.SolrCore.close [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5fb2943c
   [junit4]   2> 32375 T11 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=4,autocommit maxTime=1200ms,autocommits=1,soft 
autocommit maxTime=500ms,soft 
autocommits=2,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=1,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=633,transaction_logs_total_number=3}
   [junit4]   2> 32375 T11 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> ASYNC  NEW_CORE C2 name=collection1 
org.apache.solr.core.SolrCore@3532a880
   [junit4]   2> 32381 T11 C2 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[529 (1483135362020671488)]} 0 4
   [junit4]   2> 32882 T23 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 32961 T23 oass.SolrIndexSearcher.<init> Opening 
Searcher@48ad0abe[collection1] main
   [junit4]   2> 32962 T23 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 32962 T21 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@48ad0abe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)
 Uninverting(_2(5.0.0):c1) Uninverting(_3(5.0.0):c1)))}
   [junit4]   2> 32964 T11 C2 oasup.LogUpdateProcessor.finish [collection1] 
webapp=null path=null params={} {add=[530 (1483135362634088448)]} 0 2
   [junit4]   2> 33464 T23 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 33481 T23 oass.SolrIndexSearcher.<init> Opening 
Searcher@68d27930[collection1] main
   [junit4]   2> 33482 T23 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 33499 T21 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@68d27930[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c5)
 Uninverting(_2(5.0.0):c1) Uninverting(_3(5.0.0):c1) 
Uninverting(_4(5.0.0):c1)))}
   [junit4]   2> 33582 T22 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 33584 T22 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-65d637e7))),segFN=segments_5,generation=5}
   [junit4]   2> 33584 T22 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 5
   [junit4]   2> 33585 T22 oasc.SolrCore.openNewSearcher SolrIndexSearcher has 
not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 33585 T22 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 36786 T11 oas.SolrTestCaseJ4.tearDown ###Ending 
testSoftAndHardCommitMaxTimeMixedAdds
   [junit4]   2> 36790 T11 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 36791 T11 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=1756174045
   [junit4]   2> 36791 T11 oasc.SolrCore.close [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3532a880
   [junit4]   2> 36792 T11 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=3,autocommit maxTime=1200ms,autocommits=1,soft 
autocommit maxTime=500ms,soft 
autocommits=2,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=838,transaction_logs_total_number=4}
   [junit4]   2> 36793 T11 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 36793 T11 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 36794 T11 oasu.DefaultSolrCoreState.closeIndexWriter closing 
IndexWriter with IndexWriterCloser
   [junit4]   2> 36795 T11 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> 36797 T11 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 36797 T11 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001;done=false>>]
   [junit4]   2> 36798 T11 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001
   [junit4]   2> 36798 T11 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 36799 T11 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest-BCADA386AC22601D-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): 
{timestamp=Lucene50(blocksize=128), multiDefault=Lucene50(blocksize=128), 
range_facet_l=Lucene50(blocksize=128), 
intDefault=PostingsFormat(name=MockRandom), 
id=PostingsFormat(name=LuceneVarGapFixedInterval), 
text=PostingsFormat(name=Memory doPackFST= false), 
_version_=PostingsFormat(name=MockRandom), subject=Lucene50(blocksize=128)}, 
docValues:{timestamp=DocValuesFormat(name=Lucene50)}, 
sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ga_IE, 
timezone=Australia/Currie
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 
(64-bit)/cpus=16,threads=1,free=131545632,total=246415360
   [junit4]   2> NOTE: All tests run in this JVM: [SoftAutoCommitTest]
   [junit4] Completed on J3 in 37.84s, 3 tests, 1 failure <<< FAILURES!

[...truncated 1434 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:525:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:473:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:189:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:496:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1359:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:966:
 There were test failures: 438 suites, 1800 tests, 1 failure, 58 ignored (22 
assumptions)

Total time: 66 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #2184
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 163187830 bytes
Compression is 0.0%
Took 28 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to