lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (64bit/ibm-j9-jdk7) - Build # 10359 - Failure!
Date Thu, 05 Jun 2014 10:35:27 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/10359/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

1 tests failed.
REGRESSION:  org.apache.solr.update.AutoCommitTest.testMaxTime

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([8D6D6C13E93E6DCA:179911F177A4F1F6]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:704)
	at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:227)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:94)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
	at java.lang.reflect.Method.invoke(Method.java:619)
	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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java: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:360)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
	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:43)
	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:360)
	at java.lang.Thread.run(Thread.java:853)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=0]
	xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">1259</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><int name="range_facet_si">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="range_facet_sl"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1470065634368815104</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-06-05T10:23:58.929Z</date></doc></result>
</response>

	request was:rows=20&start=0&qt=standard&version=2.2&q=id:529
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:697)
	... 42 more




Build Log:
[...truncated 11486 lines...]
   [junit4] Suite: org.apache.solr.update.AutoCommitTest
   [junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001
   [junit4]   2> 812849 T2185 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
   [junit4]   2> 812849 T2185 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 812850 T2185 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 812850 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 812850 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 812851 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 812883 T2185 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 812925 T2185 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 812926 T2185 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 812932 T2185 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 813219 T2185 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 813220 T2185 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 813221 T2185 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 813225 T2185 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 813227 T2185 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 813232 T2185 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 813235 T2185 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 813238 T2185 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 813239 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 813239 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 813239 T2185 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 813240 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 813240 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 813240 T2185 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 813241 T2185 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 813241 T2185 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/'
   [junit4]   2> 813276 T2185 oasc.CoreContainer.<init> New CoreContainer 1653311091
   [junit4]   2> 813276 T2185 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/]
   [junit4]   2> 813277 T2185 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 813277 T2185 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
   [junit4]   2> 813278 T2185 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 813278 T2185 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 813278 T2185 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 813279 T2185 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 813279 T2185 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 813279 T2185 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 813279 T2185 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 813283 T2185 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 813283 T2185 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 813283 T2185 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 813287 T2186 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 813288 T2186 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 813288 T2186 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 813289 T2186 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 813333 T2186 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 813379 T2186 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 813380 T2186 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 813385 T2186 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 813676 T2186 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 813676 T2186 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 813677 T2186 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 813681 T2186 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 813683 T2186 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 813688 T2186 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 813691 T2186 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 813694 T2186 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 813695 T2186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 813695 T2186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 813695 T2186 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 813695 T2186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 813696 T2186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 813696 T2186 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 813696 T2186 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 813696 T2186 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/
   [junit4]   2> 813697 T2186 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e76e5979
   [junit4]   2> 813697 T2186 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001
   [junit4]   2> 813698 T2186 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index/
   [junit4]   2> 813698 T2186 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index' doesn't exist. Creating new index...
   [junit4]   2> 813698 T2186 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index
   [junit4]   2> 813699 T2186 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 813699 T2186 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_1,generation=1}
   [junit4]   2> 813699 T2186 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 813700 T2186 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 813701 T2186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 813702 T2186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 813702 T2186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 813702 T2186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 813703 T2186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 813703 T2186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 813703 T2186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 813703 T2186 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 813704 T2186 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 813704 T2186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 813704 T2186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 813705 T2186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 813705 T2186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 813705 T2186 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 813705 T2186 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 813706 T2186 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 813706 T2186 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 813706 T2186 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 813706 T2186 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 813707 T2186 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 813707 T2186 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 813707 T2186 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 813708 T2186 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 813708 T2186 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 813708 T2186 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 813709 T2186 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 813709 T2186 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 813709 T2186 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 813709 T2186 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 813710 T2186 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 813710 T2186 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 813710 T2186 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 813711 T2186 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 813711 T2186 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 813711 T2186 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 813712 T2186 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 813713 T2186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 813714 T2186 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 813716 T2186 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 813716 T2186 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 813717 T2186 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=19, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4775204158719152]
   [junit4]   2> 813717 T2186 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_1,generation=1}
   [junit4]   2> 813717 T2186 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 813718 T2186 oass.SolrIndexSearcher.<init> Opening Searcher@7eb3fc72[collection1] main
   [junit4]   2> 813718 T2186 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 813718 T2186 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 813719 T2186 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
   [junit4]   2> 813719 T2186 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 813719 T2186 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 813719 T2186 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 813872 T2186 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 813894 T2187 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7eb3fc72[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 813896 T2186 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 813897 T2185 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 813901 T2185 oas.SolrTestCaseJ4.setUp ###Starting testMaxTime
   [junit4]   2> ASYNC  NEW_CORE C546 name=collection1 org.apache.solr.core.SolrCore@ef3937ef
   [junit4]   2> 813901 T2185 C546 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1470065633263616000)} 0 0
   [junit4]   2> 813905 T2185 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 813905 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 813906 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 813906 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 813944 T2185 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 813987 T2185 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 813987 T2185 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 813993 T2185 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 814275 T2185 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 814276 T2185 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 814277 T2185 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 814281 T2185 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 814283 T2185 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 814288 T2185 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 814291 T2185 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 814294 T2185 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 814295 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 814295 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 814295 T2185 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 814296 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 814296 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 814297 T2185 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 814297 T2185 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/
   [junit4]   2> 814297 T2185 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e76e5979
   [junit4]   2> 814298 T2185 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index/
   [junit4]   2> 814298 T2185 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 814301 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 814301 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 814302 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 814302 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 814302 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 814303 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 814303 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 814304 T2185 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 814304 T2185 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 814304 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 814305 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 814305 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 814306 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 814306 T2185 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 814306 T2185 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 814307 T2185 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 814307 T2185 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 814308 T2185 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 814308 T2185 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 814309 T2185 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 814309 T2185 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 814310 T2185 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 814310 T2185 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 814311 T2185 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 814311 T2185 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 814311 T2185 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 814312 T2185 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 814313 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 814313 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 814314 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 814314 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 814315 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 814315 T2185 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 814316 T2185 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 814316 T2185 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 814317 T2185 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 814318 T2185 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 814319 T2185 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 814321 T2185 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 814321 T2185 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 814321 T2185 oass.SolrIndexSearcher.<init> Opening Searcher@4b958a2e[collection1] main
   [junit4]   2> 814322 T2185 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 814322 T2185 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 814323 T2185 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
   [junit4]   2> 814323 T2185 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 814323 T2185 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 814324 T2185 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 814325 T2185 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 814330 T2189 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4b958a2e[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 814335 T2185 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
   [junit4]   2> 814335 T2185 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 814335 T2185 oasu.DefaultSolrCoreState.closeIndexWriter Closing old IndexWriter... core=collection1
   [junit4]   2> 814336 T2185 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 814337 T2185 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_1,generation=1}
   [junit4]   2> 814337 T2185 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 814337 T2185 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
   [junit4]   2> 814338 T2185 oass.SolrIndexSearcher.<init> Opening Searcher@25848e61[collection1] main
   [junit4]   2> 814338 T2185 oasc.CoreContainer.registerCore replacing core: collection1
   [junit4]   2> 814338 T2185 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@ef3937ef
   [junit4]   2> 814339 T2189 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25848e61[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 814343 T2185 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> 814344 T2185 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 814956 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1470065634368815104)]} 0 610
   [junit4]   2> 815956 T2190 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 815958 T2190 oass.SolrIndexSearcher.<init> Opening Searcher@ecb0ab9a[collection1] main
   [junit4]   2> 815958 T2190 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 815959 T2189 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ecb0ab9a[collection1] main{StandardDirectoryReader(segments_1:3:nrt _0(4.9):c1)}
   [junit4]   2> ASYNC  NEW_CORE C547 name=collection1 org.apache.solr.core.SolrCore@c89e3ef4
   [junit4]   2> 816216 T2185 C547 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:529} hits=1 status=0 QTime=1259 
   [junit4]   2> 816242 T2185 oas.SolrTestCaseJ4.assertQ ERROR REQUEST FAILED: xpath=//result[@numFound=0]
   [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">1259</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><int name="range_facet_si">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="range_facet_sl"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1470065634368815104</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-06-05T10:23:58.929Z</date></doc></result>
   [junit4]   2> 	</response>
   [junit4]   2> 	
   [junit4]   2> 		request was:rows=20&start=0&qt=standard&version=2.2&q=id:529
   [junit4]   2> 816243 T2185 oasc.SolrException.log ERROR REQUEST FAILED: rows=20&start=0&qt=standard&version=2.2&q=id:529:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=0]
   [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">1259</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><int name="range_facet_si">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="range_facet_sl"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1470065634368815104</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-06-05T10:23:58.929Z</date></doc></result>
   [junit4]   2> 	</response>
   [junit4]   2> 	
   [junit4]   2> 		request was:rows=20&start=0&qt=standard&version=2.2&q=id:529
   [junit4]   2> 		at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:697)
   [junit4]   2> 		at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:227)
   [junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:94)
   [junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
   [junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:619)
   [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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
   [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:360)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
   [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:43)
   [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:360)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:853)
   [junit4]   2> 	
   [junit4]   2> 816244 T2185 oas.SolrTestCaseJ4.tearDown ###Ending testMaxTime
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=8D6D6C13E93E6DCA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_JO -Dtests.timezone=America/Hermosillo -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   2.35s J0 | AutoCommitTest.testMaxTime <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([8D6D6C13E93E6DCA:179911F177A4F1F6]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:704)
   [junit4]    > 	at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:227)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:853)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=0]
   [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">1259</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><int name="range_facet_si">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="range_facet_sl"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1470065634368815104</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-06-05T10:23:58.929Z</date></doc></result>
   [junit4]    > </response>
   [junit4]    > 	request was:rows=20&start=0&qt=standard&version=2.2&q=id:529
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:697)
   [junit4]    > 	... 42 more
   [junit4]   2> 816251 T2185 oas.SolrTestCaseJ4.setUp ###Starting testMaxDocs
   [junit4]   2> 816252 T2185 C547 oass.SolrIndexSearcher.<init> Opening Searcher@f55a3251[collection1] realtime
   [junit4]   2> 816252 T2185 C547 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1470065635727769600)} 0 1
   [junit4]   2> 816519 T2185 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 816520 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 816520 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 816521 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 816558 T2185 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 816600 T2185 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 816601 T2185 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 816607 T2185 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 816903 T2185 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 816904 T2185 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 816904 T2185 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 816909 T2185 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 816911 T2185 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 816916 T2185 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 816919 T2185 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 816923 T2185 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 816923 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 816923 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 816924 T2185 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 816924 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 816925 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 816925 T2185 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 816925 T2185 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/
   [junit4]   2> 816926 T2185 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e76e5979
   [junit4]   2> 816927 T2185 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index/
   [junit4]   2> 816927 T2185 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 816929 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 816930 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 816930 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 816931 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 816931 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 816931 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 816932 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 816932 T2185 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 816932 T2185 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 816933 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 816933 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 816934 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 816934 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 816934 T2185 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 816935 T2185 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 816935 T2185 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 816936 T2185 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 816936 T2185 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 816937 T2185 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 816937 T2185 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 816938 T2185 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 816938 T2185 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 816939 T2185 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 816939 T2185 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 816939 T2185 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 816940 T2185 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 816940 T2185 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 816941 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 816942 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 816942 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 816943 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 816943 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 816944 T2185 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 816944 T2185 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 816945 T2185 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 816945 T2185 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 816946 T2185 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 816948 T2185 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 816949 T2185 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 816949 T2185 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 816950 T2185 oass.SolrIndexSearcher.<init> Opening Searcher@d2a0f822[collection1] main
   [junit4]   2> 816950 T2185 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 816951 T2185 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 816951 T2185 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
   [junit4]   2> 816952 T2185 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 816952 T2185 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 816952 T2185 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 816953 T2185 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 816959 T2191 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d2a0f822[collection1] main{StandardDirectoryReader(segments_1:5:nrt)}
   [junit4]   2> 816963 T2185 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
   [junit4]   2> 816963 T2185 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 816964 T2185 oasu.DefaultSolrCoreState.closeIndexWriter Closing old IndexWriter... core=collection1
   [junit4]   2> 816964 T2185 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_2,generation=2}
   [junit4]   2> 816964 T2185 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 816965 T2185 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 816966 T2185 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_2,generation=2}
   [junit4]   2> 816967 T2185 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 816967 T2185 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
   [junit4]   2> 816968 T2185 oass.SolrIndexSearcher.<init> Opening Searcher@3bd4bf46[collection1] main
   [junit4]   2> 816968 T2185 oasc.CoreContainer.registerCore replacing core: collection1
   [junit4]   2> 816968 T2185 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@c89e3ef4
   [junit4]   2> 816969 T2191 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3bd4bf46[collection1] main{StandardDirectoryReader(segments_2:5:nrt)}
   [junit4]   2> 816973 T2185 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommit maxTime=1000ms,soft autocommits=1,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=0,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 816973 T2185 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 817003 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[0 (1470065636516298752)]} 0 28
   [junit4]   2> 817004 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[1 (1470065636517347328)]} 0 28
   [junit4]   2> 817004 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[2 (1470065636517347329)]} 0 28
   [junit4]   2> 817005 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[3 (1470065636518395904)]} 0 28
   [junit4]   2> 817005 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[4 (1470065636518395905)]} 0 28
   [junit4]   2> 817006 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[5 (1470065636519444480)]} 0 28
   [junit4]   2> 817006 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[6 (1470065636519444481)]} 0 28
   [junit4]   2> 817007 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[7 (1470065636520493056)]} 0 28
   [junit4]   2> 817007 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[8 (1470065636520493057)]} 0 28
   [junit4]   2> 817008 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[9 (1470065636521541632)]} 0 28
   [junit4]   2> 817008 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[10 (1470065636521541633)]} 0 28
   [junit4]   2> 817009 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[11 (1470065636522590208)]} 0 28
   [junit4]   2> 817009 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[12 (1470065636522590209)]} 0 28
   [junit4]   2> 817010 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[13 (1470065636523638784)]} 0 28
   [junit4]   2> ASYNC  NEW_CORE C548 name=collection1 org.apache.solr.core.SolrCore@f33f4e6c
   [junit4]   2> 817015 T2185 C548 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:1} hits=0 status=0 QTime=5 
   [junit4]   2> 817029 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[14 (1470065636542513152)]} 0 28
   [junit4]   2> 817030 T2192 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 817032 T2192 oass.SolrIndexSearcher.<init> Opening Searcher@ee1ed067[collection1] main
   [junit4]   2> 817032 T2192 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 817034 T2191 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ee1ed067[collection1] main{StandardDirectoryReader(segments_2:7:nrt _1(4.9):c15)}
   [junit4]   2> 817280 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[15 (1470065636806754304)]} 0 28
   [junit4]   2> 817281 T2185 C548 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:14} hits=1 status=0 QTime=0 
   [junit4]   2> 817283 T2185 C548 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:15} hits=0 status=0 QTime=0 
   [junit4]   2> 817284 T2185 oas.SolrTestCaseJ4.tearDown ###Ending testMaxDocs
   [junit4]   2> 817289 T2185 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithin
   [junit4]   2> 817292 T2185 C548 oass.SolrIndexSearcher.<init> Opening Searcher@d8d88c10[collection1] realtime
   [junit4]   2> 817293 T2185 C548 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1470065636817240064)} 0 3
   [junit4]   2> 817295 T2185 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 817296 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 817297 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 817297 T2185 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 817336 T2185 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_9
   [junit4]   2> 817379 T2185 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 817380 T2185 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 817385 T2185 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 817672 T2185 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 817673 T2185 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 817673 T2185 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 817677 T2185 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 817680 T2185 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 817685 T2185 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 817688 T2185 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 817691 T2185 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 817691 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 817692 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 817692 T2185 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 817692 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 817693 T2185 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 817693 T2185 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 817694 T2185 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/
   [junit4]   2> 817694 T2185 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@e76e5979
   [junit4]   2> 817695 T2185 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index/
   [junit4]   2> 817695 T2185 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
   [junit4]   2> 817697 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 817698 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 817698 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 817699 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 817699 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 817699 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 817700 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 817700 T2185 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
   [junit4]   2> 817700 T2185 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
   [junit4]   2> 817701 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 817701 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 817702 T2185 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 817702 T2185 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 817702 T2185 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 817703 T2185 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 817703 T2185 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 817704 T2185 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 817704 T2185 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 817705 T2185 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
   [junit4]   2> 817705 T2185 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 817706 T2185 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 817706 T2185 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 817707 T2185 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 817707 T2185 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 817707 T2185 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 817708 T2185 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 817708 T2185 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 817709 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 817709 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 817710 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 817711 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 817711 T2185 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 817712 T2185 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 817712 T2185 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 817713 T2185 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 817713 T2185 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 817714 T2185 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 817716 T2185 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 817717 T2185 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 817717 T2185 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 817718 T2185 oass.SolrIndexSearcher.<init> Opening Searcher@c27f9458[collection1] main
   [junit4]   2> 817719 T2185 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 817719 T2185 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 817719 T2185 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
   [junit4]   2> 817720 T2185 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
   [junit4]   2> 817720 T2185 oasr.RestManager.init Initializing 0 registered ManagedResources
   [junit4]   2> 817720 T2185 oashc.SpellCheckComponent.inform Initializing spell checkers
   [junit4]   2> 817721 T2185 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 817727 T2193 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c27f9458[collection1] main{StandardDirectoryReader(segments_2:11:nrt)}
   [junit4]   2> 817731 T2185 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
   [junit4]   2> 817732 T2185 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 817732 T2185 oasu.DefaultSolrCoreState.closeIndexWriter Closing old IndexWriter... core=collection1
   [junit4]   2> 817733 T2185 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_3,generation=3}
   [junit4]   2> 817733 T2185 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 817733 T2185 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=29.0302734375, floorSegmentMB=0.609375, forceMergeDeletesPctAllowed=6.7179561825089635, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 817734 T2185 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_3,generation=3}
   [junit4]   2> 817734 T2185 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 817735 T2185 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
   [junit4]   2> 817735 T2185 oass.SolrIndexSearcher.<init> Opening Searcher@bd2a19ae[collection1] main
   [junit4]   2> 817736 T2185 oasc.CoreContainer.registerCore replacing core: collection1
   [junit4]   2> 817736 T2185 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@f33f4e6c
   [junit4]   2> 817737 T2193 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bd2a19ae[collection1] main{StandardDirectoryReader(segments_3:11:nrt)}
   [junit4]   2> 817741 T2185 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> 817742 T2185 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 817765 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1470065637314265088)]} 0 22
   [junit4]   2> ASYNC  NEW_CORE C549 name=collection1 org.apache.solr.core.SolrCore@81e4d14b
   [junit4]   2> 817766 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:529} hits=0 status=0 QTime=0 
   [junit4]   2> 821765 T2194 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 821767 T2194 oass.SolrIndexSearcher.<init> Opening Searcher@712350c3[collection1] main
   [junit4]   2> 821767 T2194 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 821768 T2193 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@712350c3[collection1] main{StandardDirectoryReader(segments_3:13:nrt _3(4.9):c1)}
   [junit4]   2> 821780 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[530 (1470065641524297728)]} 0 22
   [junit4]   2> 821780 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:530} hits=0 status=0 QTime=0 
   [junit4]   2> 821782 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[529 (-1470065641527443456)]} 0 22
   [junit4]   2> 821782 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:529} hits=1 status=0 QTime=0 
   [junit4]   2> 822782 T2194 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 822784 T2194 oass.SolrIndexSearcher.<init> Opening Searcher@f3070cd4[collection1] main
   [junit4]   2> 822784 T2194 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 822785 T2193 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f3070cd4[collection1] main{StandardDirectoryReader(segments_3:17:nrt _4(4.9):c1)}
   [junit4]   2> 823034 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:529} hits=0 status=0 QTime=0 
   [junit4]   2> 823035 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:530} hits=1 status=0 QTime=0 
   [junit4]   2> 823037 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642842357760)]} 0 22
   [junit4]   2> 823037 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642843406336)]} 0 22
   [junit4]   2> 823038 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642844454912)]} 0 22
   [junit4]   2> 823038 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642844454913)]} 0 22
   [junit4]   2> 823039 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642844454914)]} 0 22
   [junit4]   2> 823039 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642845503488)]} 0 22
   [junit4]   2> 823039 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642845503489)]} 0 22
   [junit4]   2> 823040 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642846552064)]} 0 22
   [junit4]   2> 823040 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642846552065)]} 0 22
   [junit4]   2> 823041 T2185 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1470065642847600640)]} 0 22
   [junit4]   2> 823041 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:500} hits=0 status=0 QTime=0 
   [junit4]   2> 823042 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642848649216)]} 0 22
   [junit4]   2> 823043 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642849697792)]} 0 22
   [junit4]   2> 823043 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642849697793)]} 0 22
   [junit4]   2> 823043 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642849697794)]} 0 22
   [junit4]   2> 823044 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642850746368)]} 0 22
   [junit4]   2> 823044 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642850746369)]} 0 22
   [junit4]   2> 823044 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642850746370)]} 0 22
   [junit4]   2> 823045 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642851794944)]} 0 22
   [junit4]   2> 823045 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642851794945)]} 0 22
   [junit4]   2> 823046 T2185 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1470065642851794946)]} 0 22
   [junit4]   2> 823046 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:530} hits=1 status=0 QTime=0 
   [junit4]   2> 824042 T2194 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 824044 T2194 oass.SolrIndexSearcher.<init> Opening Searcher@5ddb129d[collection1] main
   [junit4]   2> 824044 T2194 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 824045 T2193 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5ddb129d[collection1] main{StandardDirectoryReader(segments_3:21:nrt _5(4.9):c10/9:delGen=1)}
   [junit4]   2> 824048 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:500} hits=1 status=0 QTime=1 
   [junit4]   2> 824049 T2185 C549 oasc.SolrCore.execute [collection1] webapp=null path=null params={rows=20&start=0&qt=standard&version=2.2&q=id:530} hits=0 status=0 QTime=0 
   [junit4]   2> 824050 T2185 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithin
   [junit4]   2> 824051 T2185 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 824052 T2185 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1653311091
   [junit4]   2> 824052 T2185 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@81e4d14b
   [junit4]   2> 824059 T2185 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=0,errors=0,cumulative_adds=12,cumulative_deletesById=11,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
   [junit4]   2> 824060 T2185 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 824060 T2185 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 824061 T2185 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 824061 T2185 C549 oasu.DirectUpdateHandler2.closeWriter Committing on IndexWriter close.
   [junit4]   2> 824061 T2185 C549 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@af5fca84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bac5d66b)),segFN=segments_4,generation=4}
   [junit4]   2> 824062 T2185 C549 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 824062 T2185 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 824063 T2185 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 824063 T2185 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001;done=false>>]
   [junit4]   2> 824063 T2185 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001
   [junit4]   2> 824063 T2185 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 824064 T2185 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solr.update.AutoCommitTest-8D6D6C13E93E6DCA-001
   [junit4]   2> NOTE: test params are: codec=Lucene41, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=ar_JO, timezone=America/Hermosillo
   [junit4]   2> NOTE: Linux 3.8.0-41-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=72811984,total=215351296
   [junit4]   2> NOTE: All tests run in this JVM: [TestBadConfig, CustomCollectionTest, OverseerCollectionProcessorTest, CopyFieldTest, SuggesterTest, TestReloadAndDeleteDocs, BlockDirectoryTest, TestSerializedLuceneMatchVersion, TestClassNameShortening, ZkControllerTest, HdfsRecoveryZkTest, SyncSliceTest, HdfsCollectionsAPIDistributedZkTest, TestFaceting, HdfsBasicDistributedZk2Test, TestRequestStatusCollectionAPI, TestSystemIdResolver, AsyncMigrateRouteKeyTest, UpdateParamsTest, DirectUpdateHandlerOptimizeTest, DistributedQueryComponentOptimizationTest, TestHighFrequencyDictionaryFactory, DeleteInactiveReplicaTest, URLClassifyProcessorTest, BasicDistributedZkTest, TestCursorMarkWithoutUniqueKey, SliceStateUpdateTest, TestDocSet, TestPerFieldSimilarity, DistributedSpellCheckComponentTest, TestFieldCollectionResource, DocExpirationUpdateProcessorFactoryTest, SpellPossibilityIteratorTest, TestSuggestSpellingConverter, LegacyHTMLStripCharFilterTest, TestTrie, TestDistribDocBasedVersion, TestNRTOpen, CircularListTest, TestPseudoReturnFields, SolrTestCaseJ4Test, TestStressVersions, QueryEqualityTest, AnalysisAfterCoreReloadTest, DistributedDebugComponentTest, TestRemoteStreaming, TestRealTimeGet, TestWriterPerf, ClusterStateTest, FileBasedSpellCheckerTest, BJQParserTest, SolrXmlInZkTest, MergeStrategyTest, UUIDFieldTest, TestFieldSortValues, SimpleFacetsTest, TestJoin, TestRandomDVFaceting, TestRandomMergePolicy, TestSolrQueryParser, SoftAutoCommitTest, TestRestManager, AtomicUpdatesTest, PrimitiveFieldTypeTest, DocValuesTest, TestExpandComponent, TestSort, SliceStateTest, CollectionsAPIAsyncDistributedZkTest, PolyFieldTest, RecoveryZkTest, SearchHandlerTest, SynonymTokenizerTest, DeleteShardTest, BinaryUpdateRequestHandlerTest, ZkCLITest, DistributedQueryElevationComponentTest, DOMUtilTest, TestElisionMultitermQuery, ChangedSchemaMergeTest, SOLR749Test, TestUniqueKeyFieldResource, TestLRUCache, TestPostingsSolrHighlighter, IndexSchemaTest, BlockCacheTest, TestMaxScoreQueryParser, SuggesterFSTTest, TestFunctionQuery, RemoteQueryErrorTest, TestPHPSerializedResponseWriter, SampleTest, DocumentBuilderTest, LeaderElectionIntegrationTest, TestReplicationHandler, LeaderElectionTest, ShardRoutingCustomTest, AutoCommitTest]
   [junit4] Completed on J0 in 11.24s, 3 tests, 1 error <<< FAILURES!

[...truncated 576 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:460: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1297: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:921: There were test failures: 397 suites, 1640 tests, 1 error, 93 ignored (15 assumptions)

Total time: 62 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



Mime
View raw message