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/11/14 05:29:25 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk7) - Build #
11610 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11610/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;} (asserts: true)
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([DE1A8C04CF37BCCD:44EEF1E651AD20F1]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:723)
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:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java: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">1902</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_">1484719069957455872</long><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2014-11-14T04:14:04.097Z</date></doc></result>
</response>
request was:start=0&q=id:529&rows=20&qt=standard&version=2.2
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
... 41 more
Build Log:
[...truncated 11010 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-DE1A8C04CF37BCCD-001/init-core-data-001
[junit4] 2> 190412 T514 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 190413 T514 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 190413 T514 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 190414 T514 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> 190414 T514 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> 190415 T514 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> 190467 T514 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 190547 T514 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 190548 T514 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 190556 T514 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 190969 T514 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 190976 T514 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 190979 T514 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 190989 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 190994 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 190998 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 190999 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 190999 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 191000 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 191001 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 191001 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 191002 T514 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 191002 T514 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> 191003 T514 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/'
[junit4] 2> 191062 T514 oasc.CoreContainer.<init> New CoreContainer 1510273709
[junit4] 2> 191062 T514 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/]
[junit4] 2> 191064 T514 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 191064 T514 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 191065 T514 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 191065 T514 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 191066 T514 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 191066 T514 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 191066 T514 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 191067 T514 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 191067 T514 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 191068 T514 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 191069 T514 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 191069 T514 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 191070 T514 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 191070 T514 oasc.CoreContainer.load Host Name:
[junit4] 2> 191074 T515 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 191075 T515 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> 191075 T515 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> 191076 T515 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> 191109 T515 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 191156 T515 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 191156 T515 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 191162 T515 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 191606 T515 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 191614 T515 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 191618 T515 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 191628 T515 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 191633 T515 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 191639 T515 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 191639 T515 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 191640 T515 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 191640 T515 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 191641 T515 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 191641 T515 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 191641 T515 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> 191642 T515 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 191642 T515 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-DE1A8C04CF37BCCD-001/init-core-data-001/
[junit4] 2> 191643 T515 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@42b288df
[junit4] 2> 191644 T515 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-DE1A8C04CF37BCCD-001/init-core-data-001
[junit4] 2> 191644 T515 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-DE1A8C04CF37BCCD-001/init-core-data-001/index/
[junit4] 2> 191644 T515 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-DE1A8C04CF37BCCD-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 191645 T515 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-DE1A8C04CF37BCCD-001/init-core-data-001/index
[junit4] 2> 191645 T515 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.47537444579038]
[junit4] 2> 191646 T515 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_1,generation=1}
[junit4] 2> 191647 T515 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 191647 T515 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 191650 T515 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 191651 T515 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 191651 T515 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 191652 T515 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 191652 T515 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 191652 T515 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 191653 T515 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 191653 T515 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 191654 T515 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 191654 T515 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 191655 T515 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 191655 T515 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 191656 T515 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 191656 T515 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 191657 T515 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 191657 T515 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 191658 T515 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 191658 T515 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 191659 T515 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 191659 T515 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 191660 T515 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 191660 T515 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 191661 T515 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 191661 T515 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 191662 T515 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 191662 T515 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 191663 T515 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 191663 T515 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 191664 T515 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 191664 T515 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 191665 T515 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 191665 T515 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 191666 T515 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 191666 T515 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 191667 T515 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 191667 T515 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 191668 T515 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 191668 T515 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 191669 T515 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 191671 T515 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 191673 T515 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 191674 T515 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 191676 T515 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 191678 T515 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 191681 T515 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 191681 T515 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 191682 T515 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=35.134765625, floorSegmentMB=0.70703125, forceMergeDeletesPctAllowed=10.340606605404501, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8070870434318352
[junit4] 2> 191682 T515 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_1,generation=1}
[junit4] 2> 191683 T515 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 191683 T515 oass.SolrIndexSearcher.<init> Opening Searcher@33cd051[collection1] main
[junit4] 2> 191684 T515 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 191684 T515 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 191685 T515 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 191685 T515 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 191685 T515 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 191686 T515 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 191695 T515 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 191720 T516 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@33cd051[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 191724 T515 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 191726 T514 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 191730 T514 oas.SolrTestCaseJ4.setUp ###Starting testMaxTime
[junit4] 2> ASYNC NEW_CORE C390 name=collection1 org.apache.solr.core.SolrCore@5331e525
[junit4] 2> 191732 T514 C390 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1484719068721184768)} 0 1
[junit4] 2> 191737 T514 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 191738 T514 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> 191738 T514 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> 191739 T514 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> 191796 T514 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 191871 T514 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 191872 T514 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 191881 T514 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 192254 T514 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 192261 T514 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 192264 T514 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 192273 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 192278 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 192283 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 192283 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 192284 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 192284 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 192285 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 192286 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 192286 T514 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> 192287 T514 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-DE1A8C04CF37BCCD-001/init-core-data-001/
[junit4] 2> 192287 T514 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@42b288df
[junit4] 2> 192288 T514 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-DE1A8C04CF37BCCD-001/init-core-data-001/index/
[junit4] 2> 192289 T514 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 192292 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 192293 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 192294 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 192294 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 192295 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 192295 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 192296 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 192296 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 192297 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 192298 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 192298 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 192299 T514 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 192300 T514 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 192300 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 192301 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 192302 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 192302 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 192303 T514 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 192304 T514 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 192304 T514 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 192305 T514 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 192306 T514 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 192307 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 192307 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 192308 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 192309 T514 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 192309 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 192310 T514 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 192311 T514 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 192312 T514 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 192312 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 192313 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 192314 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 192315 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 192316 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 192316 T514 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 192317 T514 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 192318 T514 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 192319 T514 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 192321 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 192322 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 192324 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 192326 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 192328 T514 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 192330 T514 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 192331 T514 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 192332 T514 oass.SolrIndexSearcher.<init> Opening Searcher@d4bc4400[collection1] main
[junit4] 2> 192332 T514 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 192333 T514 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 192334 T514 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 192334 T514 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 192335 T514 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 192335 T514 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 192337 T514 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 192349 T518 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d4bc4400[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 192357 T514 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 192358 T514 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 192358 T514 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 192359 T514 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=52.09375, floorSegmentMB=1.1005859375, forceMergeDeletesPctAllowed=9.035585229273503, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 192360 T514 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_1,generation=1}
[junit4] 2> 192361 T514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 192361 T514 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 192362 T514 oass.SolrIndexSearcher.<init> Opening Searcher@3109df78[collection1] main
[junit4] 2> 192363 T514 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 192363 T514 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5331e525
[junit4] 2> 192364 T514 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> 192364 T518 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3109df78[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 192364 T514 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 192912 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1484719069957455872)]} 0 529
[junit4] 2> 193911 T519 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 193966 T519 oass.SolrIndexSearcher.<init> Opening Searcher@c461f2cc[collection1] main
[junit4] 2> 193967 T519 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 194037 T518 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c461f2cc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))}
[junit4] 2> ASYNC NEW_CORE C391 name=collection1 org.apache.solr.core.SolrCore@dff11c4
[junit4] 2> 194814 T514 C391 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:529&rows=20&qt=standard&version=2.2} hits=1 status=0 QTime=1902
[junit4] 2> 194868 T514 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">1902</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_">1484719069957455872</long><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2014-11-14T04:14:04.097Z</date></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:start=0&q=id:529&rows=20&qt=standard&version=2.2
[junit4] 2> 194869 T514 oasc.SolrException.log ERROR REQUEST FAILED: start=0&q=id:529&rows=20&qt=standard&version=2.2: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">1902</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_">1484719069957455872</long><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2014-11-14T04:14:04.097Z</date></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:start=0&q=id:529&rows=20&qt=standard&version=2.2
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
[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:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:853)
[junit4] 2>
[junit4] 2> 194870 T514 oas.SolrTestCaseJ4.tearDown ###Ending testMaxTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=DE1A8C04CF37BCCD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nb_NO -Dtests.timezone=PRC -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 3.16s J1 | AutoCommitTest.testMaxTime <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([DE1A8C04CF37BCCD:44EEF1E651AD20F1]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:723)
[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">1902</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_">1484719069957455872</long><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2014-11-14T04:14:04.097Z</date></doc></result>
[junit4] > </response>
[junit4] > request was:start=0&q=id:529&rows=20&qt=standard&version=2.2
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
[junit4] > ... 41 more
[junit4] 2> 194886 T514 oas.SolrTestCaseJ4.setUp ###Starting testMaxDocs
[junit4] 2> 194888 T514 C391 oass.SolrIndexSearcher.<init> Opening Searcher@f7a0ef32[collection1] realtime
[junit4] 2> 194889 T514 C391 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1484719072030490624)} 0 2
[junit4] 2> 194923 T514 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 194924 T514 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> 194924 T514 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> 194925 T514 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> 194971 T514 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 195051 T514 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 195052 T514 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 195061 T514 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 195504 T514 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 195511 T514 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 195515 T514 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 195524 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 195529 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 195534 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 195535 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 195535 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 195536 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 195537 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 195537 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 195538 T514 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> 195538 T514 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-DE1A8C04CF37BCCD-001/init-core-data-001/
[junit4] 2> 195539 T514 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@42b288df
[junit4] 2> 195540 T514 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-DE1A8C04CF37BCCD-001/init-core-data-001/index/
[junit4] 2> 195541 T514 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 195545 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 195545 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 195546 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 195546 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 195547 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 195547 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 195548 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 195549 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 195549 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 195550 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 195550 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 195551 T514 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 195552 T514 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 195553 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 195553 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 195554 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 195555 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 195555 T514 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 195556 T514 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 195557 T514 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 195557 T514 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 195558 T514 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 195559 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 195560 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 195560 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 195561 T514 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 195562 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 195562 T514 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 195563 T514 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 195564 T514 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 195565 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 195565 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 195566 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 195567 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 195568 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 195568 T514 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 195569 T514 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 195570 T514 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 195571 T514 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 195573 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 195574 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 195576 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 195578 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 195580 T514 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 195582 T514 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 195582 T514 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 195583 T514 oass.SolrIndexSearcher.<init> Opening Searcher@496cc277[collection1] main
[junit4] 2> 195584 T514 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 195585 T514 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 195585 T514 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 195586 T514 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 195586 T514 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 195587 T514 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 195589 T514 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 195600 T520 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@496cc277[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 195608 T514 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 195608 T514 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 195609 T514 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 195610 T514 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_2,generation=2}
[junit4] 2> 195610 T514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 195611 T514 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=738673923, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 195614 T514 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_2,generation=2}
[junit4] 2> 195614 T514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 195615 T514 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 195616 T514 oass.SolrIndexSearcher.<init> Opening Searcher@ce1f8cce[collection1] main
[junit4] 2> 195617 T514 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 195617 T514 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@dff11c4
[junit4] 2> 195617 T514 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> 195618 T520 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ce1f8cce[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 195620 T514 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 195950 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[0 (1484719073145126912)]} 0 313
[junit4] 2> 195951 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[1 (1484719073146175488)]} 0 313
[junit4] 2> 195952 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[2 (1484719073147224064)]} 0 313
[junit4] 2> 195953 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[3 (1484719073148272640)]} 0 313
[junit4] 2> 195954 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[4 (1484719073149321216)]} 0 313
[junit4] 2> 195955 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[5 (1484719073150369792)]} 0 313
[junit4] 2> 195956 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[6 (1484719073151418368)]} 0 313
[junit4] 2> 195957 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[7 (1484719073151418369)]} 0 313
[junit4] 2> 195958 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[8 (1484719073152466944)]} 0 313
[junit4] 2> 195958 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[9 (1484719073153515520)]} 0 313
[junit4] 2> 195959 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[10 (1484719073154564096)]} 0 313
[junit4] 2> 195960 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[11 (1484719073155612672)]} 0 313
[junit4] 2> 195961 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[12 (1484719073156661248)]} 0 313
[junit4] 2> 195962 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[13 (1484719073157709824)]} 0 313
[junit4] 2> ASYNC NEW_CORE C392 name=collection1 org.apache.solr.core.SolrCore@7b6822a3
[junit4] 2> 196564 T514 C392 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:1&rows=20&qt=standard&version=2.2} hits=0 status=0 QTime=601
[junit4] 2> 196585 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[14 (1484719073809924096)]} 0 313
[junit4] 2> 196586 T521 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 196592 T521 oass.SolrIndexSearcher.<init> Opening Searcher@a3f5a90b[collection1] main
[junit4] 2> 196598 T520 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a3f5a90b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C15)))}
[junit4] 2> 196598 T521 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 196837 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[15 (1484719074074165248)]} 0 313
[junit4] 2> 196838 T514 C392 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:14&rows=20&qt=standard&version=2.2} hits=1 status=0 QTime=1
[junit4] 2> 196840 T514 C392 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:15&rows=20&qt=standard&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 196841 T514 oas.SolrTestCaseJ4.tearDown ###Ending testMaxDocs
[junit4] 2> 196847 T514 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithin
[junit4] 2> 196856 T514 C392 oass.SolrIndexSearcher.<init> Opening Searcher@f5ed23d[collection1] realtime
[junit4] 2> 196857 T514 C392 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1484719074086748160)} 0 9
[junit4] 2> 196859 T514 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 196860 T514 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> 196861 T514 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> 196861 T514 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> 196919 T514 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 197008 T514 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 197009 T514 oass.IndexSchema.readSchema Reading Solr Schema from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 197018 T514 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 197470 T514 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 197481 T514 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 197485 T514 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 197495 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 197501 T514 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 197506 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 197507 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 197508 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 197508 T514 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 197509 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 197510 T514 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 197511 T514 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> 197511 T514 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-DE1A8C04CF37BCCD-001/init-core-data-001/
[junit4] 2> 197512 T514 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@42b288df
[junit4] 2> 197513 T514 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-DE1A8C04CF37BCCD-001/init-core-data-001/index/
[junit4] 2> 197514 T514 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 197519 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 197519 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 197520 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 197521 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 197521 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 197522 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 197522 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 197523 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 197524 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 197525 T514 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 197525 T514 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 197526 T514 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 197527 T514 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 197528 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 197529 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 197530 T514 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 197530 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 197531 T514 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 197532 T514 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 197533 T514 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 197534 T514 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 197535 T514 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 197536 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 197537 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 197538 T514 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 197538 T514 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 197539 T514 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 197540 T514 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 197541 T514 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 197542 T514 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 197542 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 197543 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 197544 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 197545 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 197546 T514 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 197547 T514 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 197548 T514 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 197549 T514 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 197550 T514 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 197552 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 197554 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 197556 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 197558 T514 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 197560 T514 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 197563 T514 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 197563 T514 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 197565 T514 oass.SolrIndexSearcher.<init> Opening Searcher@f9c4feb6[collection1] main
[junit4] 2> 197565 T514 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 197566 T514 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 197567 T514 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 197567 T514 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 197568 T514 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 197569 T514 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 197571 T514 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 197584 T522 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f9c4feb6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 197593 T514 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 197594 T514 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 197594 T514 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4] 2> 197595 T514 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_3,generation=3}
[junit4] 2> 197596 T514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 197597 T514 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@92dd297d
[junit4] 2> 197599 T514 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_3,generation=3}
[junit4] 2> 197599 T514 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 197601 T514 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 197601 T514 oass.SolrIndexSearcher.<init> Opening Searcher@488df0c7[collection1] main
[junit4] 2> 197602 T514 oasc.CoreContainer.registerCore replacing core: collection1
[junit4] 2> 197603 T514 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7b6822a3
[junit4] 2> 197603 T522 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@488df0c7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 197603 T514 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> 197604 T514 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 198152 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1484719075453042688)]} 0 527
[junit4] 2> ASYNC NEW_CORE C393 name=collection1 org.apache.solr.core.SolrCore@56865afb
[junit4] 2> 198153 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:529&rows=20&qt=standard&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 202152 T523 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 202156 T523 oass.SolrIndexSearcher.<init> Opening Searcher@f93cd5b9[collection1] main
[junit4] 2> 202156 T523 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 202157 T522 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f93cd5b9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):C1)))}
[junit4] 2> 202169 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[530 (1484719079665172480)]} 0 527
[junit4] 2> 202170 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:530&rows=20&qt=standard&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 202172 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[529 (-1484719079669366784)]} 0 527
[junit4] 2> 202172 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:529&rows=20&qt=standard&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 203172 T523 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 203178 T523 oass.SolrIndexSearcher.<init> Opening Searcher@4ead9dc9[collection1] main
[junit4] 2> 203178 T523 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 203180 T522 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ead9dc9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):C1)))}
[junit4] 2> 203425 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:529&rows=20&qt=standard&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 203426 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:530&rows=20&qt=standard&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 203428 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080986378240)]} 0 527
[junit4] 2> 203429 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080987426816)]} 0 527
[junit4] 2> 203429 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080987426817)]} 0 527
[junit4] 2> 203430 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080988475392)]} 0 527
[junit4] 2> 203430 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080988475393)]} 0 527
[junit4] 2> 203431 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080989523968)]} 0 527
[junit4] 2> 203431 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080989523969)]} 0 527
[junit4] 2> 203432 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080990572544)]} 0 527
[junit4] 2> 203432 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080990572545)]} 0 527
[junit4] 2> 203433 T514 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484719080991621120)]} 0 527
[junit4] 2> 203433 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:500&rows=20&qt=standard&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 203434 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080992669696)]} 0 527
[junit4] 2> 203435 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080993718272)]} 0 527
[junit4] 2> 203435 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080993718273)]} 0 527
[junit4] 2> 203436 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080994766848)]} 0 527
[junit4] 2> 203436 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080994766849)]} 0 527
[junit4] 2> 203437 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080995815424)]} 0 527
[junit4] 2> 203437 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080995815425)]} 0 527
[junit4] 2> 203438 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080996864000)]} 0 527
[junit4] 2> 203438 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080996864001)]} 0 527
[junit4] 2> 203439 T514 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484719080997912576)]} 0 527
[junit4] 2> 203439 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:530&rows=20&qt=standard&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 204435 T523 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 204438 T523 oass.SolrIndexSearcher.<init> Opening Searcher@55570908[collection1] main
[junit4] 2> 204438 T523 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 204441 T522 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@55570908[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.0.0):C10/9:delGen=1)))}
[junit4] 2> 204442 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:500&rows=20&qt=standard&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 204445 T514 C393 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:530&rows=20&qt=standard&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 204447 T514 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithin
[junit4] 2> 204449 T514 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 204449 T514 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1510273709
[junit4] 2> 204450 T514 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@56865afb
[junit4] 2> 204450 T514 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> 204450 T514 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 204450 T514 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 204451 T514 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 204451 T514 C393 oasu.DirectUpdateHandler2.closeWriter Committing on IndexWriter close.
[junit4] 2> 204452 T514 C393 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@88817f13 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b0ea15a)),segFN=segments_4,generation=4}
[junit4] 2> 204452 T514 C393 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 204453 T514 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 204454 T514 oasc.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo WARN Could not getStatistics on info bean org.apache.solr.search.SolrIndexSearcher org.apache.lucene.store.AlreadyClosedException: this IndexReader is closed
[junit4] 2> at org.apache.lucene.index.IndexReader.ensureOpen(IndexReader.java:274)
[junit4] 2> at org.apache.lucene.index.StandardDirectoryReader.getVersion(StandardDirectoryReader.java:326)
[junit4] 2> at org.apache.lucene.index.FilterDirectoryReader.getVersion(FilterDirectoryReader.java:110)
[junit4] 2> at org.apache.lucene.index.FilterDirectoryReader.getVersion(FilterDirectoryReader.java:110)
[junit4] 2> at org.apache.solr.search.SolrIndexSearcher.getStatistics(SolrIndexSearcher.java:2243)
[junit4] 2> at org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo(JmxMonitoredMap.java:244)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1816)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1607)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.objectInstancesFromFilteredNamedObjects(DefaultMBeanServerInterceptor.java:1568)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.queryMBeansImpl(DefaultMBeanServerInterceptor.java:525)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.queryNames(DefaultMBeanServerInterceptor.java:538)
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.queryNames(JmxMBeanServer.java:631)
[junit4] 2> at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:116)
[junit4] 2> at org.apache.solr.core.SolrCore.close(SolrCore.java:1125)
[junit4] 2> at org.apache.solr.core.SolrCores.close(SolrCores.java:117)
[junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:366)
[junit4] 2> at org.apache.solr.util.TestHarness.close(TestHarness.java:337)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:634)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:185)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor31.invoke(Unknown Source)
[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$5.evaluate(RandomizedRunner.java:790)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.ru
[...truncated too long message...]
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> 204469 T514 oasc.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo WARN Could not getStatistics on info bean org.apache.solr.search.SolrIndexSearcher org.apache.lucene.store.AlreadyClosedException: this IndexReader is closed
[junit4] 2> at org.apache.lucene.index.IndexReader.ensureOpen(IndexReader.java:274)
[junit4] 2> at org.apache.lucene.index.StandardDirectoryReader.getVersion(StandardDirectoryReader.java:326)
[junit4] 2> at org.apache.lucene.index.FilterDirectoryReader.getVersion(FilterDirectoryReader.java:110)
[junit4] 2> at org.apache.lucene.index.FilterDirectoryReader.getVersion(FilterDirectoryReader.java:110)
[junit4] 2> at org.apache.solr.search.SolrIndexSearcher.getStatistics(SolrIndexSearcher.java:2243)
[junit4] 2> at org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo(JmxMonitoredMap.java:244)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1816)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1607)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1825)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:442)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:427)
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:558)
[junit4] 2> at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:120)
[junit4] 2> at org.apache.solr.core.SolrCore.close(SolrCore.java:1125)
[junit4] 2> at org.apache.solr.core.SolrCores.close(SolrCores.java:117)
[junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:366)
[junit4] 2> at org.apache.solr.util.TestHarness.close(TestHarness.java:337)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:634)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:185)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor31.invoke(Unknown Source)
[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$5.evaluate(RandomizedRunner.java:790)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:853)
[junit4] 2>
[junit4] 2> 204471 T514 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 204471 T514 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-DE1A8C04CF37BCCD-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-DE1A8C04CF37BCCD-001/init-core-data-001;done=false>>]
[junit4] 2> 204471 T514 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-DE1A8C04CF37BCCD-001/init-core-data-001
[junit4] 2> 204471 T514 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-DE1A8C04CF37BCCD-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-DE1A8C04CF37BCCD-001/init-core-data-001/index;done=false>>]
[junit4] 2> 204472 T514 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-DE1A8C04CF37BCCD-001/init-core-data-001/index
[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-DE1A8C04CF37BCCD-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {intDefault=PostingsFormat(name=LuceneFixedGap), _version_=PostingsFormat(name=LuceneFixedGap), timestamp=FSTOrd50, range_facet_l=FSTOrd50, multiDefault=FSTOrd50, field_t=PostingsFormat(name=Direct), id=PostingsFormat(name=Direct), subject=FSTOrd50, text=PostingsFormat(name=MockRandom)}, docValues:{timestamp=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=nb_NO, timezone=PRC
[junit4] 2> NOTE: Linux 3.13.0-39-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=12883760,total=86179840
[junit4] 2> NOTE: All tests run in this JVM: [DistributedQueryElevationComponentTest, QueryParsingTest, TestSweetSpotSimilarityFactory, IndexSchemaRuntimeFieldTest, MigrateRouteKeyTest, TestManagedSchema, StatsComponentTest, TestSolrConfigHandlerConcurrent, SynonymTokenizerTest, TestSolrXml, DocValuesMissingTest, UUIDFieldTest, PolyFieldTest, DistanceFunctionTest, TestDistributedGrouping, BJQParserTest, TestValueSourceCache, DeleteShardTest, ExternalCollectionsTest, TestInitParams, AddSchemaFieldsUpdateProcessorFactoryTest, SuggesterFSTTest, TestPHPSerializedResponseWriter, ScriptEngineTest, SharedFSAutoReplicaFailoverUtilsTest, TestSerializedLuceneMatchVersion, OutputWriterTest, TestExtendedDismaxParser, TestDistribDocBasedVersion, LegacyHTMLStripCharFilterTest, DistributedQueueTest, TermVectorComponentDistributedTest, AnalysisAfterCoreReloadTest, TestUpdate, MergeStrategyTest, TestDistributedMissingSort, TestElisionMultitermQuery, ZkSolrClientTest, SOLR749Test, DebugComponentTest, SchemaVersionSpecificBehaviorTest, SortByFunctionTest, TestCharFilters, LeaderInitiatedRecoveryOnCommitTest, LoggingHandlerTest, TestJoin, TermVectorComponentTest, TestPostingsSolrHighlighter, CircularListTest, AutoCommitTest]
[junit4] Completed on J1 in 14.09s, 3 tests, 1 error <<< FAILURES!
[...truncated 1113 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:525: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:486: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:186: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:508: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1358: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:965: There were test failures: 447 suites, 1820 tests, 1 error, 121 ignored (21 assumptions)
Total time: 71 minutes 2 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;} (asserts: true)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any