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 2015/01/28 05:11:15 UTC
[JENKINS] Lucene-Solr-5.0-Linux (64bit/jdk1.9.0-ea-b47) - Build #
59 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.0-Linux/59/
Java: 64bit/jdk1.9.0-ea-b47 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.update.AutoCommitTest.testMaxTime
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([1EBC586928A64AD8:8448258BB63CD6E4]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:748)
at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:237)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
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)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
</response>
request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:741)
... 41 more
Build Log:
[...truncated 9161 lines...]
[junit4] Suite: org.apache.solr.update.AutoCommitTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001
[junit4] 2> 53449 T578 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 53450 T578 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 53450 T578 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 53450 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 53450 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 53451 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 53476 T578 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 53496 T578 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 53521 T578 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 53522 T578 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 53526 T578 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 53640 T578 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 53644 T578 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 53645 T578 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 53649 T578 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 53651 T578 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 53653 T578 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 53653 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 53653 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 53653 T578 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 53654 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 53654 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 53654 T578 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 53654 T578 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr
[junit4] 2> 53654 T578 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 53663 T578 oasc.CoreContainer.<init> New CoreContainer 1636434913
[junit4] 2> 53663 T578 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 53663 T578 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 600000
[junit4] 2> 53663 T578 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 53663 T578 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 60000
[junit4] 2> 53664 T578 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 53664 T578 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 53664 T578 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 53664 T578 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 53665 T578 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 53665 T578 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 53665 T578 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 53665 T578 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 53666 T578 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 53666 T578 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 53666 T578 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 53667 T578 oasc.CoreContainer.load Host Name: null
[junit4] 2> 53668 T579 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 53668 T579 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 53669 T579 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 53669 T579 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 53685 T579 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 53697 T579 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 53727 T579 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 53727 T579 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 53732 T579 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 53869 T579 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 53874 T579 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 53875 T579 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 53881 T579 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 53883 T579 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 53885 T579 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 53885 T579 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 53885 T579 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 53885 T579 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 53886 T579 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 53886 T579 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 53886 T579 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 53886 T579 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 53886 T579 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/
[junit4] 2> 53886 T579 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 53887 T579 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001
[junit4] 2> 53887 T579 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index/
[junit4] 2> 53887 T579 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 53887 T579 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index
[junit4] 2> 53887 T579 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=43.82421875, floorSegmentMB=0.3232421875, forceMergeDeletesPctAllowed=25.452137733241788, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3075429714402236
[junit4] 2> 53888 T579 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_1,generation=1}
[junit4] 2> 53888 T579 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 53888 T579 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 53889 T579 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 53889 T579 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 53889 T579 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 53890 T579 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 53890 T579 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 53890 T579 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 53890 T579 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 53890 T579 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 53890 T579 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 53891 T579 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 53891 T579 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 53891 T579 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 53891 T579 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 53891 T579 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 53891 T579 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 53891 T579 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 53892 T579 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 53892 T579 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 53892 T579 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 53892 T579 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 53892 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 53892 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 53893 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 53893 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 53893 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 53893 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 53893 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 53893 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 53894 T579 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 53894 T579 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 53894 T579 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 53894 T579 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 53894 T579 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 53894 T579 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 53894 T579 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 53894 T579 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 53895 T579 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 53895 T579 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 53895 T579 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 53895 T579 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 53895 T579 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 53895 T579 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 53895 T579 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 53895 T579 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 53896 T579 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 53896 T579 oasc.RequestHandlers.initHandlersFromConfig created /search-facet-def: solr.SearchHandler
[junit4] 2> 53896 T579 oasc.RequestHandlers.initHandlersFromConfig created /search-facet-invariants: solr.SearchHandler
[junit4] 2> 53898 T579 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 53899 T579 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 53899 T579 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 53900 T579 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 53901 T579 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 53901 T579 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 53902 T579 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 53902 T579 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 53902 T579 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=111455925, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 53903 T579 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_1,generation=1}
[junit4] 2> 53903 T579 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 53903 T579 oass.SolrIndexSearcher.<init> Opening Searcher@625af304[collection1] main
[junit4] 2> 53903 T579 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 53903 T579 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 53903 T579 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 53904 T579 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 53959 T579 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 53981 T579 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 53981 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 53982 T579 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 53982 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 53982 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 53982 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 53983 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 53983 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 53983 T578 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 53984 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 53984 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 53985 T580 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 53985 T580 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@625af304[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 53986 T578 oas.SolrTestCaseJ4.setUp ###Starting testMaxDocs
[junit4] 2> ASYNC NEW_CORE C153 name=collection1 org.apache.solr.core.SolrCore@5b40864c
[junit4] 2> 53991 T578 C153 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1491511427232432128)} 0 5
[junit4] 2> 53994 T578 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 53994 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 53995 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 53995 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 54021 T578 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 54034 T578 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 54070 T578 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 54071 T578 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 54075 T578 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 54206 T578 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 54212 T578 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 54214 T578 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 54221 T578 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 54224 T578 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 54226 T578 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 54227 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 54227 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 54228 T578 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 54228 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 54228 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 54229 T578 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 54229 T578 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/
[junit4] 2> 54229 T578 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 54230 T578 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index/
[junit4] 2> 54230 T578 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 54231 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 54232 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 54232 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 54232 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 54233 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 54233 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 54233 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 54234 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 54234 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 54235 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 54235 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 54235 T578 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 54236 T578 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 54236 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 54236 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 54236 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 54237 T578 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 54237 T578 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 54238 T578 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 54238 T578 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 54238 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 54239 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 54239 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 54239 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 54240 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 54240 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 54240 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 54241 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 54241 T578 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 54241 T578 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 54242 T578 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 54242 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 54242 T578 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 54243 T578 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 54243 T578 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 54243 T578 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54243 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54244 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54244 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54244 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54245 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54245 T578 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54245 T578 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54245 T578 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 54246 T578 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 54246 T578 oasc.RequestHandlers.initHandlersFromConfig created /search-facet-def: solr.SearchHandler
[junit4] 2> 54246 T578 oasc.RequestHandlers.initHandlersFromConfig created /search-facet-invariants: solr.SearchHandler
[junit4] 2> 54249 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 54250 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 54253 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 54254 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 54255 T578 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 54257 T578 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 54259 T578 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 54259 T578 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 54260 T578 oass.SolrIndexSearcher.<init> Opening Searcher@67da043b[collection1] main
[junit4] 2> 54260 T578 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 54261 T578 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 54261 T578 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 54261 T578 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 54263 T578 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 54268 T578 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 54268 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 54269 T578 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 54269 T578 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 54269 T578 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 54269 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 54270 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 54270 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 54270 T578 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=83.74609375, floorSegmentMB=1.421875, forceMergeDeletesPctAllowed=24.26477324486885, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3594353937705831
[junit4] 2> 54270 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 54271 T578 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_1,generation=1}
[junit4] 2> 54272 T578 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 54272 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 54272 T578 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 54272 T578 oasc.SolrCore.getSearcher WARN [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 54273 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 54273 T578 oass.SolrIndexSearcher.<init> Opening Searcher@92991cd[collection1] main
[junit4] 2> 54273 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 54274 T582 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 54274 T582 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67da043b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 54277 T578 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 54277 T582 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@92991cd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 54277 T578 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5b40864c
[junit4] 2> 54278 T578 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=1,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 54279 T578 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 54285 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[0 (1491511427542810624)]} 0 4
[junit4] 2> 54286 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[1 (1491511427547004928)]} 0 4
[junit4] 2> 54287 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[2 (1491511427547004929)]} 0 4
[junit4] 2> 54287 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[3 (1491511427548053504)]} 0 4
[junit4] 2> 54291 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[4 (1491511427548053505)]} 0 4
[junit4] 2> 54292 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[5 (1491511427553296384)]} 0 4
[junit4] 2> 54292 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[6 (1491511427553296385)]} 0 4
[junit4] 2> 54293 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[7 (1491511427554344960)]} 0 4
[junit4] 2> 54293 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[8 (1491511427554344961)]} 0 4
[junit4] 2> 54294 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[9 (1491511427555393536)]} 0 4
[junit4] 2> 54294 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[10 (1491511427555393537)]} 0 4
[junit4] 2> 54295 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[11 (1491511427556442112)]} 0 4
[junit4] 2> 54296 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[12 (1491511427556442113)]} 0 4
[junit4] 2> 54296 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[13 (1491511427557490688)]} 0 4
[junit4] 2> ASYNC NEW_CORE C154 name=collection1 org.apache.solr.core.SolrCore@5cff3da1
[junit4] 2> 54297 T578 C154 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1
[junit4] 2> 54299 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[14 (1491511427559587840)]} 0 4
[junit4] 2> 54300 T583 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 54311 T583 oass.SolrIndexSearcher.<init> Opening Searcher@45c7ba50[collection1] main
[junit4] 2> 54314 T582 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@45c7ba50[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c15)))}
[junit4] 2> 54314 T583 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 54551 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[15 (1491511427823828992)]} 0 4
[junit4] 2> 54551 T578 C154 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 54553 T578 C154 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:15&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 54554 T578 oas.SolrTestCaseJ4.tearDown ###Ending testMaxDocs
[junit4] 2> 54557 T578 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithin
[junit4] 2> 54567 T578 C154 oass.SolrIndexSearcher.<init> Opening Searcher@386fe372[collection1] realtime
[junit4] 2> 54568 T578 C154 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1491511427831169024)} 0 11
[junit4] 2> 54568 T578 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 54569 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 54569 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 54569 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 54590 T578 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 54609 T578 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 54641 T578 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 54642 T578 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 54649 T578 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 54776 T578 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 54780 T578 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 54781 T578 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 54785 T578 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 54787 T578 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 54789 T578 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 54789 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 54790 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 54790 T578 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 54790 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 54790 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 54790 T578 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 54791 T578 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/
[junit4] 2> 54791 T578 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 54791 T578 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index/
[junit4] 2> 54791 T578 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 54792 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 54792 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 54793 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 54793 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 54793 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 54793 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 54793 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 54794 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 54794 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 54794 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 54795 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 54796 T578 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 54796 T578 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 54796 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 54797 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 54797 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 54797 T578 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 54797 T578 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 54798 T578 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 54798 T578 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 54798 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 54802 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 54802 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 54803 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 54803 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 54803 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 54804 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 54804 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 54804 T578 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 54805 T578 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 54805 T578 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 54805 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 54805 T578 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 54806 T578 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 54806 T578 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 54807 T578 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54807 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54808 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54808 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54808 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54808 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54809 T578 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54809 T578 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 54809 T578 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 54809 T578 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 54810 T578 oasc.RequestHandlers.initHandlersFromConfig created /search-facet-def: solr.SearchHandler
[junit4] 2> 54810 T578 oasc.RequestHandlers.initHandlersFromConfig created /search-facet-invariants: solr.SearchHandler
[junit4] 2> 54812 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 54813 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 54814 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 54815 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 54816 T578 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 54816 T578 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 54817 T578 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 54817 T578 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 54817 T578 oass.SolrIndexSearcher.<init> Opening Searcher@34d43976[collection1] main
[junit4] 2> 54817 T578 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 54818 T578 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 54818 T578 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 54818 T578 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 54819 T578 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 54823 T578 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 54823 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 54823 T578 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 54824 T578 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 54824 T578 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 54824 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 54824 T578 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_2,generation=2}
[junit4] 2> 54825 T578 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 54824 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 54825 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 54825 T578 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 54826 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 54826 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 54827 T578 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_2,generation=2}
[junit4] 2> 54827 T578 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 54827 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 54827 T578 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 54827 T578 oasc.SolrCore.getSearcher WARN [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 54828 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 54828 T578 oass.SolrIndexSearcher.<init> Opening Searcher@226a9204[collection1] main
[junit4] 2> 54828 T578 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 54828 T578 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5cff3da1
[junit4] 2> 54828 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 54828 T578 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommit maxDocs=14,soft autocommits=1,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=16,adds=1,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=16,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 54829 T584 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34d43976[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 54829 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 54829 T578 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 54830 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 54830 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 54830 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 54831 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 54831 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 54832 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 54832 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 54833 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1491511428118478848)]} 0 3
[junit4] 2> 54833 T584 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 54833 T584 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@226a9204[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> ASYNC NEW_CORE C155 name=collection1 org.apache.solr.core.SolrCore@1225b6bb
[junit4] 2> 54834 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1
[junit4] 2> 58832 T585 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 58846 T585 oass.SolrIndexSearcher.<init> Opening Searcher@44186a30[collection1] main
[junit4] 2> 58846 T585 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 58846 T584 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@44186a30[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.0.0):c1)))}
[junit4] 2> 59087 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[530 (1491511432580169728)]} 0 3
[junit4] 2> 59088 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1
[junit4] 2> 59089 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[529 (-1491511432582266880)]} 0 3
[junit4] 2> 59089 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 60089 T585 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 60094 T585 oass.SolrIndexSearcher.<init> Opening Searcher@19273fbc[collection1] main
[junit4] 2> 60094 T585 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 60095 T584 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19273fbc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.0.0):c1)))}
[junit4] 2> 60341 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1
[junit4] 2> 60342 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 60343 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433898229760)]} 0 3
[junit4] 2> 60344 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433899278336)]} 0 3
[junit4] 2> 60344 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433899278337)]} 0 3
[junit4] 2> 60345 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433900326912)]} 0 3
[junit4] 2> 60345 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433900326913)]} 0 3
[junit4] 2> 60345 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433900326914)]} 0 3
[junit4] 2> 60346 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433901375488)]} 0 3
[junit4] 2> 60346 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433901375489)]} 0 3
[junit4] 2> 60346 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433901375490)]} 0 3
[junit4] 2> 60347 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1491511433902424064)]} 0 3
[junit4] 2> 60348 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1
[junit4] 2> 60348 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433903472640)]} 0 3
[junit4] 2> 60349 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433904521216)]} 0 3
[junit4] 2> 60349 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433904521217)]} 0 3
[junit4] 2> 60349 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433904521218)]} 0 3
[junit4] 2> 60350 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433905569792)]} 0 3
[junit4] 2> 60350 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433905569793)]} 0 3
[junit4] 2> 60350 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433905569794)]} 0 3
[junit4] 2> 60350 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433905569795)]} 0 3
[junit4] 2> 60351 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433906618368)]} 0 3
[junit4] 2> 60351 T578 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1491511433906618369)]} 0 3
[junit4] 2> 60351 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 61348 T585 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 61354 T585 oass.SolrIndexSearcher.<init> Opening Searcher@7069a690[collection1] main
[junit4] 2> 61355 T585 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 61355 T584 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7069a690[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(5.0.0):c10/9:delGen=1)))}
[junit4] 2> 61603 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 61604 T578 C155 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 61605 T578 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithin
[junit4] 2> 61608 T578 oas.SolrTestCaseJ4.setUp ###Starting testMaxTime
[junit4] 2> 61611 T578 C155 oass.SolrIndexSearcher.<init> Opening Searcher@96eceb7[collection1] realtime
[junit4] 2> 61611 T578 C155 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1491511435225726976)} 0 2
[junit4] 2> 61612 T578 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 61613 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 61613 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 61613 T578 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 61650 T578 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 61659 T578 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0
[junit4] 2> 61680 T578 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 61681 T578 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 61685 T578 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 61821 T578 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 61826 T578 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 61828 T578 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 61835 T578 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 61837 T578 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 61840 T578 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 61841 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 61841 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 61841 T578 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 61842 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 61842 T578 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 61842 T578 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 61842 T578 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/
[junit4] 2> 61843 T578 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 61843 T578 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index/
[junit4] 2> 61843 T578 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 61845 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 61845 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 61845 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 61846 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 61846 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 61846 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 61846 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 61847 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 61847 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 61848 T578 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 61848 T578 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 61848 T578 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 61849 T578 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 61849 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 61849 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 61849 T578 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 61850 T578 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 61850 T578 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 61850 T578 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 61850 T578 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 61851 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 61852 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 61852 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 61852 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 61853 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 61853 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 61853 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 61854 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 61854 T578 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 61854 T578 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 61854 T578 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 61855 T578 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 61855 T578 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 61855 T578 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 61855 T578 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 61856 T578 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 61856 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 61856 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 61856 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 61857 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 61857 T578 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 61857 T578 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 61858 T578 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 61858 T578 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 61858 T578 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 61858 T578 oasc.RequestHandlers.initHandlersFromConfig created /search-facet-def: solr.SearchHandler
[junit4] 2> 61859 T578 oasc.RequestHandlers.initHandlersFromConfig created /search-facet-invariants: solr.SearchHandler
[junit4] 2> 61860 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 61861 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 61863 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 61865 T578 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 61866 T578 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 61866 T578 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 61867 T578 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 61867 T578 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 61868 T578 oass.SolrIndexSearcher.<init> Opening Searcher@2d35af7f[collection1] main
[junit4] 2> 61868 T578 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 61869 T578 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 61869 T578 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 61869 T578 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 61871 T578 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 61876 T578 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 61877 T578 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 61877 T578 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 61877 T578 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 61878 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 61879 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 61879 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 61879 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 61880 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 61880 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 61881 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 61882 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 61883 T586 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 61883 T578 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_3,generation=3}
[junit4] 2> 61883 T578 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 61884 T578 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=17.1259765625, floorSegmentMB=1.0888671875, forceMergeDeletesPctAllowed=3.05690474145654, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 61885 T578 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_3,generation=3}
[junit4] 2> 61885 T578 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 61886 T586 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2d35af7f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 61887 T578 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 61888 T578 oass.SolrIndexSearcher.<init> Opening Searcher@2c1636d9[collection1] main
[junit4] 2> 61888 T578 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 61888 T578 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1225b6bb
[junit4] 2> 61889 T578 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=3,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=12,adds=0,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=12,cumulative_deletesById=11,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 61889 T578 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 61892 T586 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c1636d9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 61933 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1491511435522473984)]} 0 42
[junit4] 2> ASYNC NEW_CORE C156 name=collection1 org.apache.solr.core.SolrCore@38d69b70
[junit4] 2> 61934 T578 C156 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=1
[junit4] 2> 61935 T578 oasup.LogUpdateProcessor.finish [collection1] {add=[530 (1491511435567562752)]} 0 42
[junit4] 2> 61936 T578 C156 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 61937 T578 oas.SolrTestCaseJ4.assertQ ERROR REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
[junit4] 2> 61938 T578 oasc.SolrException.log ERROR REQUEST FAILED: q=id:529&qt=standard&start=0&rows=20&version=2.2:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:741)
[junit4] 2> at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:237)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 61940 T578 oas.SolrTestCaseJ4.tearDown ###Ending testMaxTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=1EBC586928A64AD8 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_DO -Dtests.timezone=Asia/Dacca -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.34s J1 | AutoCommitTest.testMaxTime <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([1EBC586928A64AD8:8448258BB63CD6E4]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:748)
[junit4] > at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:237)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] > </response>
[junit4] > request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:741)
[junit4] > ... 41 more
[junit4] 2> 61947 T578 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 61947 T578 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1636434913
[junit4] 2> 61955 T578 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38d69b70
[junit4] 2> 61955 T578 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommit maxTime=1000ms,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,adds=2,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 61959 T578 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 61959 T578 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 61959 T578 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 61960 T578 C156 oasu.DirectUpdateHandler2.closeWriter Committing on IndexWriter close.
[junit4] 2> 61979 T578 C156 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@38e57898 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33da4f5e),segFN=segments_4,generation=4}
[junit4] 2> 61980 T578 C156 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 61983 T578 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 61983 T578 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 61984 T578 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001;done=false>>]
[junit4] 2> 61984 T578 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001
[junit4] 2> 61984 T578 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index;done=false>>]
[junit4] 2> 61984 T578 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001/init-core-data-001/index
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest 1EBC586928A64AD8-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {_version_=FST50, field_t=FSTOrd50, multiDefault=Lucene50(blocksize=128), subject=Lucene50(blocksize=128), intDefault=FST50, id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text=FSTOrd50, range_facet_l=FST50, timestamp=FST50}, docValues:{timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=es_DO, timezone=Asia/Dacca
[junit4] 2> NOTE: Linux 3.13.0-44-generic amd64/Oracle Corporation 1.9.0-ea (64-bit)/cpus=8,threads=1,free=62354448,total=127991808
[junit4] 2> NOTE: All tests run in this JVM: [EnumFieldTest, TestLuceneMatchVersion, TestFuzzyAnalyzedSuggestions, TestSolr4Spatial, HdfsBasicDistributedZkTest, HdfsRecoveryZkTest, OpenCloseCoreStressTest, CSVRequestHandlerTest, SolrCmdDistributorTest, MoreLikeThisHandlerTest, TestOverriddenPrefixQueryForCustomFieldType, DistributedFacetPivotSmallTest, TestLRUStatsCache, TestRemoteStreaming, TestMergePolicyConfig, TestDefaultSimilarityFactory, TestManagedSchemaDynamicFieldResource, CoreAdminRequestStatusTest, TestSchemaResource, AutoCommitTest]
[junit4] Completed on J1 in 8.58s, 3 tests, 1 error <<< FAILURES!
[...truncated 1326 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/build.xml:529: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/build.xml:477: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/build.xml:191: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/solr/common-build.xml:508: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/lucene/common-build.xml:1363: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.0-Linux/lucene/common-build.xml:970: There were test failures: 459 suites, 1840 tests, 1 error, 45 ignored (20 assumptions)
Total time: 68 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.9.0-ea-b47 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any