You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/08/03 12:01:13 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk7) - Build #
10952 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10952/
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([D9FA84AE2CFD88FD:430EF94CB26714C1]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:719)
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.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: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:365)
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">2892</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1475408695659593728</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-08-03T09:49:39.253Z</date></doc></result>
</response>
request was:version=2.2&qt=standard&rows=20&start=0&q=id:529
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:712)
... 41 more
Build Log:
[...truncated 11432 lines...]
[junit4] Suite: org.apache.solr.update.AutoCommitTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001
[junit4] 2> 357042 T1125 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 357042 T1125 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 357043 T1125 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 357043 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 357043 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 357044 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 357076 T1125 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 357124 T1125 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 357125 T1125 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 357131 T1125 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 357437 T1125 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 357442 T1125 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 357446 T1125 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 357451 T1125 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 357455 T1125 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 357458 T1125 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 357458 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 357459 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 357459 T1125 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 357460 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 357460 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 357461 T1125 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 357461 T1125 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr
[junit4] 2> 357461 T1125 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 357508 T1125 oasc.CoreContainer.<init> New CoreContainer -178802778
[junit4] 2> 357508 T1125 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 357509 T1125 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 357510 T1125 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 357510 T1125 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 357510 T1125 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 357510 T1125 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 357511 T1125 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 357511 T1125 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 357511 T1125 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 357511 T1125 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 357512 T1125 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 357512 T1125 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 357512 T1125 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 357513 T1125 oasc.CoreContainer.load Host Name:
[junit4] 2> 357516 T1126 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 357517 T1126 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 357517 T1126 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 357517 T1126 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 357551 T1126 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 357594 T1126 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 357594 T1126 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 357599 T1126 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 357850 T1126 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 357854 T1126 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 357856 T1126 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 357860 T1126 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 357863 T1126 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 357866 T1126 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 357866 T1126 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 357867 T1126 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 357867 T1126 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 357867 T1126 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 357867 T1126 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 357868 T1126 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 357868 T1126 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 357868 T1126 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/
[junit4] 2> 357868 T1126 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1481528
[junit4] 2> 357869 T1126 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001
[junit4] 2> 357869 T1126 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index/
[junit4] 2> 357869 T1126 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 357870 T1126 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index
[junit4] 2> 357870 T1126 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=740931249, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 357870 T1126 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_1,generation=1}
[junit4] 2> 357871 T1126 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 357871 T1126 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 357872 T1126 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 357872 T1126 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 357873 T1126 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 357873 T1126 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 357873 T1126 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 357873 T1126 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 357873 T1126 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 357874 T1126 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 357874 T1126 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 357874 T1126 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 357874 T1126 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 357875 T1126 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 357875 T1126 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 357875 T1126 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 357875 T1126 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 357876 T1126 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 357876 T1126 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 357876 T1126 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 357876 T1126 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 357877 T1126 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 357877 T1126 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 357877 T1126 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 357877 T1126 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 357878 T1126 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 357878 T1126 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 357878 T1126 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 357878 T1126 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 357879 T1126 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 357879 T1126 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 357879 T1126 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 357879 T1126 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 357880 T1126 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 357880 T1126 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 357880 T1126 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 357880 T1126 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 357881 T1126 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 357881 T1126 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 357881 T1126 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 357881 T1126 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 357882 T1126 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 357883 T1126 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 357884 T1126 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 357884 T1126 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 357885 T1126 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 357886 T1126 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 357887 T1126 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 357889 T1126 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 357889 T1126 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 357889 T1126 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=38, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15729777216097487]
[junit4] 2> 357890 T1126 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_1,generation=1}
[junit4] 2> 357890 T1126 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 357890 T1126 oass.SolrIndexSearcher.<init> Opening Searcher@517b24af[collection1] main
[junit4] 2> 357891 T1126 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 357891 T1126 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 357891 T1126 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 357891 T1126 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 357891 T1126 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 357892 T1126 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 358009 T1126 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 358033 T1127 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@517b24af[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 358038 T1126 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 358041 T1125 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 358046 T1125 oas.SolrTestCaseJ4.setUp ###Starting testMaxTime
[junit4] 2> ASYNC NEW_CORE C597 name=collection1 org.apache.solr.core.SolrCore@73821357
[junit4] 2> 358048 T1125 C597 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1475408695045128192)} 0 2
[junit4] 2> 358055 T1125 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 358056 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 358057 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 358058 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 358095 T1125 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 358146 T1125 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 358147 T1125 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 358153 T1125 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 358409 T1125 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 358413 T1125 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 358415 T1125 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 358420 T1125 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 358423 T1125 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 358425 T1125 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 358426 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 358426 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 358427 T1125 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 358427 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 358427 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 358428 T1125 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 358428 T1125 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/
[junit4] 2> 358428 T1125 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1481528
[junit4] 2> 358429 T1125 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index/
[junit4] 2> 358429 T1125 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 358431 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 358431 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 358432 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 358432 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 358432 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 358433 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 358433 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 358433 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 358434 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 358434 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 358435 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 358435 T1125 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 358435 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 358436 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 358436 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 358437 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 358437 T1125 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 358437 T1125 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 358438 T1125 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 358441 T1125 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 358442 T1125 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 358443 T1125 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 358443 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 358444 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 358444 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 358445 T1125 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 358445 T1125 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 358445 T1125 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 358446 T1125 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 358447 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 358447 T1125 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 358447 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 358448 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 358448 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 358449 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 358449 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 358450 T1125 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 358451 T1125 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 358451 T1125 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 358452 T1125 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 358453 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 358454 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 358455 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 358456 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 358458 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 358459 T1125 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 358461 T1125 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 358461 T1125 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 358462 T1125 oass.SolrIndexSearcher.<init> Opening Searcher@32e646ff[collection1] main
[junit4] 2> 358462 T1125 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 358463 T1125 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 358463 T1125 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 358463 T1125 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 358464 T1125 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 358464 T1125 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 358465 T1125 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 358470 T1129 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@32e646ff[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 358475 T1125 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 358475 T1125 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 358475 T1125 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 358476 T1125 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48668226134596504]
[junit4] 2> 358476 T1125 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_1,generation=1}
[junit4] 2> 358477 T1125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 358477 T1125 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 358477 T1125 oass.SolrIndexSearcher.<init> Opening Searcher@d55e9529[collection1] main
[junit4] 2> 358478 T1125 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 358478 T1125 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@73821357
[junit4] 2> 358479 T1129 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d55e9529[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 358482 T1125 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> 358483 T1125 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 358635 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1475408695659593728)]} 0 150
[junit4] 2> 359635 T1130 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 359637 T1130 oass.SolrIndexSearcher.<init> Opening Searcher@d0ade47a[collection1] main
[junit4] 2> 359638 T1130 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 359638 T1129 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d0ade47a[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):c1))}
[junit4] 2> ASYNC NEW_CORE C598 name=collection1 org.apache.solr.core.SolrCore@e783c475
[junit4] 2> 361528 T1125 C598 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:529} hits=1 status=0 QTime=2892
[junit4] 2> 361606 T1125 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">2892</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1475408695659593728</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-08-03T09:49:39.253Z</date></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:version=2.2&qt=standard&rows=20&start=0&q=id:529
[junit4] 2> 361609 T1125 oasc.SolrException.log ERROR REQUEST FAILED: version=2.2&qt=standard&rows=20&start=0&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">2892</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1475408695659593728</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-08-03T09:49:39.253Z</date></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:version=2.2&qt=standard&rows=20&start=0&q=id:529
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:712)
[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.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: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:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:853)
[junit4] 2>
[junit4] 2> 361610 T1125 oas.SolrTestCaseJ4.tearDown ###Ending testMaxTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=D9FA84AE2CFD88FD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=it -Dtests.timezone=America/Danmarkshavn -Dtests.file.encoding=US-ASCII
[junit4] ERROR 3.58s J1 | AutoCommitTest.testMaxTime <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([D9FA84AE2CFD88FD:430EF94CB26714C1]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:719)
[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">2892</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1475408695659593728</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-08-03T09:49:39.253Z</date></doc></result>
[junit4] > </response>
[junit4] > request was:version=2.2&qt=standard&rows=20&start=0&q=id:529
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:712)
[junit4] > ... 41 more
[junit4] 2> 361621 T1125 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithin
[junit4] 2> 361621 T1125 C598 oass.SolrIndexSearcher.<init> Opening Searcher@735989d0[collection1] realtime
[junit4] 2> 361622 T1125 C598 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1475408698792738816)} 0 1
[junit4] 2> 361884 T1125 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 361885 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 361886 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 361887 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 361933 T1125 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 361989 T1125 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 361989 T1125 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 361995 T1125 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 362277 T1125 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 362281 T1125 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 362283 T1125 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 362288 T1125 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 362291 T1125 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 362293 T1125 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 362294 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 362294 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 362294 T1125 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 362295 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 362295 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 362295 T1125 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 362296 T1125 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/
[junit4] 2> 362296 T1125 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1481528
[junit4] 2> 362297 T1125 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index/
[junit4] 2> 362297 T1125 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 362299 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 362299 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 362300 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 362300 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 362300 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 362300 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 362301 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 362301 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 362301 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 362302 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 362302 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 362303 T1125 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 362303 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 362303 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 362304 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 362304 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 362305 T1125 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 362305 T1125 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 362305 T1125 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 362306 T1125 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 362306 T1125 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 362307 T1125 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 362307 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 362308 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 362308 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 362308 T1125 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 362309 T1125 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 362309 T1125 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 362309 T1125 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 362310 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 362310 T1125 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 362311 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 362311 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 362311 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 362312 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 362312 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 362313 T1125 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 362313 T1125 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 362314 T1125 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 362314 T1125 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 362315 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 362316 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 362317 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 362318 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 362319 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 362320 T1125 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 362322 T1125 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 362322 T1125 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 362323 T1125 oass.SolrIndexSearcher.<init> Opening Searcher@a38b0aa0[collection1] main
[junit4] 2> 362323 T1125 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 362324 T1125 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 362324 T1125 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 362324 T1125 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 362325 T1125 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 362325 T1125 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 362326 T1125 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 362331 T1131 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a38b0aa0[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 362335 T1125 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 362335 T1125 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 362335 T1125 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 362336 T1125 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_2,generation=2}
[junit4] 2> 362336 T1125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 362337 T1125 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.42504873883330097]
[junit4] 2> 362338 T1125 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_2,generation=2}
[junit4] 2> 362338 T1125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 362338 T1125 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 362339 T1125 oass.SolrIndexSearcher.<init> Opening Searcher@18a4adfc[collection1] main
[junit4] 2> 362339 T1125 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 362339 T1125 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e783c475
[junit4] 2> 362340 T1131 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18a4adfc[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 362343 T1125 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> 362344 T1125 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 362350 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1475408699556102144)]} 0 5
[junit4] 2> ASYNC NEW_CORE C599 name=collection1 org.apache.solr.core.SolrCore@e40d02fb
[junit4] 2> 362363 T1125 C599 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:529} hits=0 status=0 QTime=13
[junit4] 2> 366350 T1132 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 366353 T1132 oass.SolrIndexSearcher.<init> Opening Searcher@698aedd2[collection1] main
[junit4] 2> 366354 T1132 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 366366 T1131 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@698aedd2[collection1] main{UninvertingDirectoryReader(Uninverting(_1(5.0):c1))}
[junit4] 2> 366388 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[530 (1475408703790252032)]} 0 5
[junit4] 2> ASYNC NEW_CORE C600 name=collection1 org.apache.solr.core.SolrCore@e40d02fb
[junit4] 2> 366389 T1125 C600 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:530} hits=0 status=0 QTime=0
[junit4] 2> 366391 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[529 (-1475408703794446336)]} 0 5
[junit4] 2> 366392 T1125 C600 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:529} hits=1 status=0 QTime=0
[junit4] 2> 367391 T1132 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 367395 T1132 oass.SolrIndexSearcher.<init> Opening Searcher@bf158217[collection1] main
[junit4] 2> 367395 T1132 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 367397 T1131 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bf158217[collection1] main{UninvertingDirectoryReader(Uninverting(_2(5.0):c1))}
[junit4] 2> 367645 T1125 C600 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:529} hits=0 status=0 QTime=0
[junit4] 2> 367647 T1125 C600 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:530} hits=1 status=0 QTime=0
[junit4] 2> 367650 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705113554944)]} 0 5
[junit4] 2> 367650 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705114603520)]} 0 5
[junit4] 2> 367652 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705115652096)]} 0 5
[junit4] 2> 367653 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705117749248)]} 0 5
[junit4] 2> 367654 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705118797824)]} 0 5
[junit4] 2> 367655 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705119846400)]} 0 5
[junit4] 2> 367657 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705121943552)]} 0 5
[junit4] 2> 367658 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705122992128)]} 0 5
[junit4] 2> 367659 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705124040704)]} 0 5
[junit4] 2> 367660 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1475408705125089280)]} 0 5
[junit4] 2> 367661 T1125 C600 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:500} hits=0 status=0 QTime=0
[junit4] 2> 367664 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705128235008)]} 0 5
[junit4] 2> 367664 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705129283584)]} 0 5
[junit4] 2> 367665 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705130332160)]} 0 5
[junit4] 2> 367665 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705130332161)]} 0 5
[junit4] 2> 367666 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705131380736)]} 0 5
[junit4] 2> 367667 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705132429312)]} 0 5
[junit4] 2> 367667 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705132429313)]} 0 5
[junit4] 2> 367668 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705133477888)]} 0 5
[junit4] 2> 367669 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705133477889)]} 0 5
[junit4] 2> 367669 T1125 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1475408705134526464)]} 0 5
[junit4] 2> 367670 T1125 C600 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:530} hits=1 status=0 QTime=0
[junit4] 2> 368664 T1132 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 368667 T1132 oass.SolrIndexSearcher.<init> Opening Searcher@351bd944[collection1] main
[junit4] 2> 368668 T1132 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 368670 T1131 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@351bd944[collection1] main{UninvertingDirectoryReader(Uninverting(_3(5.0):c10/9:delGen=1))}
[junit4] 2> 368672 T1125 C600 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:500} hits=1 status=0 QTime=0
[junit4] 2> 368674 T1125 C600 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:530} hits=0 status=0 QTime=0
[junit4] 2> 368675 T1125 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithin
[junit4] 2> 368679 T1125 oas.SolrTestCaseJ4.setUp ###Starting testMaxDocs
[junit4] 2> 368680 T1125 C600 oass.SolrIndexSearcher.<init> Opening Searcher@ddff0902[collection1] realtime
[junit4] 2> 368681 T1125 C600 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1475408706193588224)} 0 2
[junit4] 2> 368682 T1125 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 368683 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 368683 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 368684 T1125 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 368716 T1125 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 368760 T1125 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 368761 T1125 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 368766 T1125 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 369060 T1125 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 369064 T1125 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 369066 T1125 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 369071 T1125 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 369074 T1125 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 369077 T1125 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 369077 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 369077 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 369078 T1125 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 369078 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 369078 T1125 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 369079 T1125 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/
[junit4] 2> 369079 T1125 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/
[junit4] 2> 369079 T1125 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c1481528
[junit4] 2> 369080 T1125 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index/
[junit4] 2> 369080 T1125 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 369082 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 369083 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 369083 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 369083 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 369084 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 369084 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 369084 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 369085 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 369085 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 369085 T1125 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 369086 T1125 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 369086 T1125 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 369087 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 369087 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 369088 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 369088 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 369088 T1125 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 369089 T1125 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 369089 T1125 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 369090 T1125 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 369090 T1125 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 369091 T1125 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 369091 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 369091 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 369092 T1125 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 369092 T1125 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 369093 T1125 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 369093 T1125 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 369093 T1125 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 369094 T1125 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 369094 T1125 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 369095 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 369095 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 369095 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 369096 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 369096 T1125 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 369097 T1125 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 369097 T1125 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 369098 T1125 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 369098 T1125 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 369099 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 369100 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 369101 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 369103 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 369104 T1125 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 369105 T1125 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 369106 T1125 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 369106 T1125 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 369107 T1125 oass.SolrIndexSearcher.<init> Opening Searcher@84e573d5[collection1] main
[junit4] 2> 369107 T1125 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 369108 T1125 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 369108 T1125 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 369108 T1125 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 369109 T1125 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 369109 T1125 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 369110 T1125 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 369116 T1133 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@84e573d5[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 369121 T1125 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 369121 T1125 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 369121 T1125 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 369122 T1125 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_3,generation=3}
[junit4] 2> 369122 T1125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 369123 T1125 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=14, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3289482187257374]
[junit4] 2> 369123 T1125 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_3,generation=3}
[junit4] 2> 369124 T1125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 369124 T1125 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 369124 T1125 oass.SolrIndexSearcher.<init> Opening Searcher@84457638[collection1] main
[junit4] 2> 369125 T1125 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 369125 T1125 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e40d02fb
[junit4] 2> 369126 T1133 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@84457638[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 369130 T1125 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> 369130 T1125 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 369144 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[0 (1475408706680127488)]} 0 12
[junit4] 2> 369144 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[1 (1475408706681176064)]} 0 12
[junit4] 2> 369145 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[2 (1475408706682224640)]} 0 12
[junit4] 2> 369145 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[3 (1475408706682224641)]} 0 12
[junit4] 2> 369146 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[4 (1475408706683273216)]} 0 12
[junit4] 2> 369146 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[5 (1475408706683273217)]} 0 12
[junit4] 2> 369147 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[6 (1475408706684321792)]} 0 12
[junit4] 2> 369148 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[7 (1475408706685370368)]} 0 12
[junit4] 2> 369149 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[8 (1475408706686418944)]} 0 12
[junit4] 2> 369150 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[9 (1475408706686418945)]} 0 12
[junit4] 2> 369150 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[10 (1475408706687467520)]} 0 12
[junit4] 2> 369151 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[11 (1475408706688516096)]} 0 12
[junit4] 2> 369152 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[12 (1475408706689564672)]} 0 12
[junit4] 2> 369153 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[13 (1475408706690613248)]} 0 12
[junit4] 2> ASYNC NEW_CORE C601 name=collection1 org.apache.solr.core.SolrCore@8d309ae2
[junit4] 2> 369153 T1125 C601 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:1} hits=0 status=0 QTime=0
[junit4] 2> 369176 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[14 (1475408706714730496)]} 0 12
[junit4] 2> 369177 T1134 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 369180 T1134 oass.SolrIndexSearcher.<init> Opening Searcher@31132b98[collection1] main
[junit4] 2> 369181 T1134 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 369183 T1133 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@31132b98[collection1] main{UninvertingDirectoryReader(Uninverting(_4(5.0):c15))}
[junit4] 2> 369428 T1125 oasup.LogUpdateProcessor.finish [collection1] {add=[15 (1475408706977923072)]} 0 12
[junit4] 2> 369429 T1125 C601 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:14} hits=1 status=0 QTime=0
[junit4] 2> 369431 T1125 C601 oasc.SolrCore.execute [collection1] webapp=null path=null params={version=2.2&qt=standard&rows=20&start=0&q=id:15} hits=0 status=0 QTime=0
[junit4] 2> 369432 T1125 oas.SolrTestCaseJ4.tearDown ###Ending testMaxDocs
[junit4] 2> 369435 T1125 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 369435 T1125 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-178802778
[junit4] 2> 369436 T1125 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@8d309ae2
[junit4] 2> 369446 T1125 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=0,errors=0,cumulative_adds=16,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 369446 T1125 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 369446 T1125 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 369453 T1125 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> ASYNC NEW_CORE C602 name=collection1 org.apache.solr.core.SolrCore@8d309ae2
[junit4] 2> 369453 T1125 C602 oasu.DirectUpdateHandler2.closeWriter Committing on IndexWriter close.
[junit4] 2> 369455 T1125 C602 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@fc558d27 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bf817c24)),segFN=segments_4,generation=4}
[junit4] 2> 369455 T1125 C602 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 369456 T1125 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 369456 T1125 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 369456 T1125 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index;done=false>>]
[junit4] 2> 369456 T1125 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001/index
[junit4] 2> 369457 T1125 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001;done=false>>]
[junit4] 2> 369457 T1125 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001/init-core-data-001
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.update.AutoCommitTest-D9FA84AE2CFD88FD-001
[junit4] 2> NOTE: test params are: codec=Lucene46, sim=DefaultSimilarity, locale=it, timezone=America/Danmarkshavn
[junit4] 2> NOTE: Linux 3.13.0-32-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=36293560,total=100859904
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrXmlPersistor, StressHdfsTest, QueryFacetTest, TestModifyConfFiles, TestJmxMonitoredMap, UpdateRequestProcessorFactoryTest, MultiThreadedOCPTest, TestMiniSolrCloudCluster, TestPseudoReturnFields, TestFieldSortValues, TestZkChroot, OverseerRolesTest, TestFreeTextSuggestions, AnalysisAfterCoreReloadTest, CollectionsAPIAsyncDistributedZkTest, BasicZkTest, TestLRUCache, DocValuesTest, SolrIndexConfigTest, TestHighFrequencyDictionaryFactory, TestExtendedDismaxParser, UUIDFieldTest, CSVRequestHandlerTest, TestQueryTypes, DistributedQueryElevationComponentTest, TestSolrQueryParserResource, InfoHandlerTest, TestCloudManagedSchema, FieldAnalysisRequestHandlerTest, TestMaxScoreQueryParser, AliasIntegrationTest, TestFunctionQuery, RequestHandlersTest, NoFacetTest, UniqFieldsUpdateProcessorFactoryTest, SolrCoreTest, CacheHeaderTest, HardAutoCommitTest, TestImplicitCoreProperties, PolyFieldTest, AsyncMigrateRouteKeyTest, TestRecovery, HdfsLockFactoryTest, DirectSolrConnectionTest, TestSolrXMLSerializer, HttpPartitionTest, UUIDUpdateProcessorFallbackTest, BasicDistributedZk2Test, TestMultiCoreConfBootstrap, SortByFunctionTest, SynonymTokenizerTest, TestLazyCores, DirectUpdateHandlerOptimizeTest, SchemaVersionSpecificBehaviorTest, DefaultValueUpdateProcessorTest, TestFieldCollectionResource, HdfsUnloadDistributedZkTest, EnumFieldTest, TestWriterPerf, ConnectionManagerTest, TestCoreContainer, TestCursorMarkWithoutUniqueKey, TestSystemIdResolver, ZkSolrClientTest, SuggesterFSTTest, ShardSplitTest, TestPostingsSolrHighlighter, TestCollectionAPI, SampleTest, TestQuerySenderNoQuery, BJQParserTest, TestPerFieldSimilarity, TermVectorComponentDistributedTest, ConvertedLegacyTest, AtomicUpdatesTest, TestPHPSerializedResponseWriter, LoggingHandlerTest, TestRandomFaceting, IndexSchemaRuntimeFieldTest, TestIBSimilarityFactory, HdfsDirectoryTest, TestBM25SimilarityFactory, TestRecoveryHdfs, DateRangeFieldTest, RankQueryTest, CoreContainerCoreInitFailuresTest, SOLR749Test, AutoCommitTest]
[junit4] Completed on J1 in 12.43s, 3 tests, 1 error <<< FAILURES!
[...truncated 780 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:460: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1298: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:922: There were test failures: 422 suites, 1756 tests, 1 error, 98 ignored (18 assumptions)
Total time: 61 minutes 34 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] 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
[JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk7) - Build #
10953 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10953/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
All tests passed
Build Log:
[...truncated 40920 lines...]