Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11588/ 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([AA7876B2A9F38E90:308C0B50376912AC]: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">1455</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_">1484275664857071616</long><date name="timestamp">2014-11-09T06:46:20.041Z</date><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int></doc></result> </response> request was:start=0&q=id:529&version=2.2&rows=20&qt=standard at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716) ... 41 more Build Log: [...truncated 10984 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-AA7876B2A9F38E90-001/init-core-data-001 [junit4] 2> 281994 T715 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true) [junit4] 2> 281995 T715 oas.SolrTestCaseJ4.initCore ####initCore [junit4] 2> 281995 T715 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 281996 T715 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> 281996 T715 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> 281996 T715 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> 282028 T715 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 282075 T715 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 282075 T715 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> 282080 T715 oass.IndexSchema.readSchema [null] Schema name=test [junit4] 2> 282330 T715 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 282334 T715 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 282336 T715 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 282342 T715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 282345 T715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 282348 T715 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 282348 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 282348 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 282349 T715 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 282349 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 282350 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 282350 T715 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 282350 T715 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> 282350 T715 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/' [junit4] 2> 282388 T715 oasc.CoreContainer.<init> New CoreContainer -1883073343 [junit4] 2> 282389 T715 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/] [junit4] 2> 282389 T715 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0 [junit4] 2> 282390 T715 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 282390 T715 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0 [junit4] 2> 282390 T715 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 282391 T715 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 282391 T715 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 282391 T715 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 282391 T715 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 282392 T715 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 282392 T715 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 282392 T715 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false [junit4] 2> 282393 T715 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 282393 T715 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 282393 T715 oasc.CoreContainer.load Host Name: [junit4] 2> 282396 T716 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 282397 T716 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> 282398 T716 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> 282398 T716 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> 282433 T716 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 282504 T716 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 282505 T716 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> 282512 T716 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 282851 T716 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 282858 T716 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 282861 T716 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 282870 T716 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 282875 T716 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 282879 T716 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 282880 T716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 282880 T716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 282881 T716 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 282881 T716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 282881 T716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 282882 T716 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> 282882 T716 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 282883 T716 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-AA7876B2A9F38E90-001/init-core-data-001/ [junit4] 2> 282883 T716 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@950e9771 [junit4] 2> 282884 T716 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-AA7876B2A9F38E90-001/init-core-data-001 [junit4] 2> 282884 T716 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-AA7876B2A9F38E90-001/init-core-data-001/index/ [junit4] 2> 282885 T716 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-AA7876B2A9F38E90-001/init-core-data-001/index' doesn't exist. Creating new index... [junit4] 2> 282885 T716 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-AA7876B2A9F38E90-001/init-core-data-001/index [junit4] 2> 282886 T716 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=94.8642578125, floorSegmentMB=0.8662109375, forceMergeDeletesPctAllowed=17.77324546016267, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6497595008745835 [junit4] 2> 282887 T716 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_1,generation=1} [junit4] 2> 282887 T716 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 282887 T716 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 282890 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 282891 T716 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 282891 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 282892 T716 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 282892 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 282892 T716 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 282893 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 282893 T716 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 282894 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 282894 T716 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 282895 T716 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 282895 T716 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 282896 T716 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 282896 T716 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 282897 T716 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 282897 T716 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 282897 T716 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 282898 T716 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 282898 T716 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 282898 T716 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 282899 T716 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 282899 T716 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 282900 T716 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 282900 T716 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 282901 T716 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 282901 T716 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 282902 T716 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 282902 T716 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 282902 T716 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 282903 T716 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 282903 T716 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 282904 T716 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 282904 T716 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 282905 T716 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 282905 T716 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 282906 T716 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 282906 T716 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 282907 T716 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 282907 T716 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 282909 T716 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 282911 T716 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 282912 T716 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 282914 T716 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 282916 T716 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 282919 T716 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 282919 T716 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 282920 T716 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5591138427634698] [junit4] 2> 282920 T716 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_1,generation=1} [junit4] 2> 282920 T716 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 282921 T716 oass.SolrIndexSearcher.<init> Opening Searcher@df33a56d[collection1] main [junit4] 2> 282921 T716 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 282922 T716 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 282922 T716 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 282922 T716 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 282923 T716 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 282923 T716 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 283067 T716 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 283086 T717 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@df33a56d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 283089 T716 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 283091 T715 oas.SolrTestCaseJ4.initCore ####initCore end [junit4] 2> 283094 T715 oas.SolrTestCaseJ4.setUp ###Starting testMaxTime [junit4] 2> ASYNC NEW_CORE C393 name=collection1 org.apache.solr.core.SolrCore@9689c3ae [junit4] 2> 283096 T715 C393 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1484275663587246080)} 0 1 [junit4] 2> 283100 T715 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 283101 T715 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> 283102 T715 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> 283102 T715 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> 283156 T715 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 283213 T715 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 283214 T715 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> 283220 T715 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 283564 T715 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 283571 T715 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 283573 T715 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 283579 T715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 283583 T715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 283586 T715 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 283587 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 283587 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 283587 T715 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 283588 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 283588 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 283589 T715 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> 283589 T715 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-AA7876B2A9F38E90-001/init-core-data-001/ [junit4] 2> 283590 T715 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@950e9771 [junit4] 2> 283591 T715 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-AA7876B2A9F38E90-001/init-core-data-001/index/ [junit4] 2> 283591 T715 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 283594 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 283594 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 283595 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 283595 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 283595 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 283596 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 283596 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 283596 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 283597 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 283597 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 283598 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 283598 T715 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 283599 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 283599 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 283600 T715 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 283600 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 283600 T715 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 283601 T715 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 283601 T715 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 283602 T715 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 283602 T715 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 283603 T715 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 283604 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 283605 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 283605 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 283606 T715 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 283607 T715 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 283607 T715 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 283608 T715 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 283609 T715 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 283609 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 283610 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 283610 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 283611 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 283611 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 283612 T715 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 283613 T715 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 283613 T715 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 283614 T715 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 283615 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 283616 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 283617 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 283619 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 283620 T715 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 283622 T715 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 283622 T715 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 283623 T715 oass.SolrIndexSearcher.<init> Opening Searcher@865d73c3[collection1] main [junit4] 2> 283623 T715 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 283624 T715 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 283624 T715 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 283624 T715 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 283625 T715 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 283625 T715 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 283627 T715 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 283636 T719 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@865d73c3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 283641 T715 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 283641 T715 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 283641 T715 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 283642 T715 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 283643 T715 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_1,generation=1} [junit4] 2> 283643 T715 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 283643 T715 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 283644 T715 oass.SolrIndexSearcher.<init> Opening Searcher@8ba4a0fb[collection1] main [junit4] 2> 283644 T715 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 283645 T715 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@9689c3ae [junit4] 2> 283645 T715 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> 283645 T719 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8ba4a0fb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 283645 T715 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 284307 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1484275664857071616)]} 0 650 [junit4] 2> 285307 T720 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 285311 T720 oass.SolrIndexSearcher.<init> Opening Searcher@8fa1b184[collection1] main [junit4] 2> 285312 T720 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 285313 T719 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8fa1b184[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))} [junit4] 2> ASYNC NEW_CORE C394 name=collection1 org.apache.solr.core.SolrCore@3d44e98 [junit4] 2> 285763 T715 C394 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:529&version=2.2&rows=20&qt=standard} hits=1 status=0 QTime=1455 [junit4] 2> 285776 T715 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">1455</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_">1484275664857071616</long><date name="timestamp">2014-11-09T06:46:20.041Z</date><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int></doc></result> [junit4] 2> </response> [junit4] 2> [junit4] 2> request was:start=0&q=id:529&version=2.2&rows=20&qt=standard [junit4] 2> 285777 T715 oasc.SolrException.log ERROR REQUEST FAILED: start=0&q=id:529&version=2.2&rows=20&qt=standard: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">1455</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_">1484275664857071616</long><date name="timestamp">2014-11-09T06:46:20.041Z</date><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int></doc></result> [junit4] 2> </response> [junit4] 2> [junit4] 2> request was:start=0&q=id:529&version=2.2&rows=20&qt=standard [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> 285778 T715 oas.SolrTestCaseJ4.tearDown ###Ending testMaxTime [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=AA7876B2A9F38E90 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nl -Dtests.timezone=America/Curacao -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 2.70s J1 | AutoCommitTest.testMaxTime <<< [junit4] > Throwable #1: java.lang.RuntimeException: Exception during query [junit4] > at __randomizedtesting.SeedInfo.seed([AA7876B2A9F38E90:308C0B50376912AC]: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">1455</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_">1484275664857071616</long><date name="timestamp">2014-11-09T06:46:20.041Z</date><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int></doc></result> [junit4] > </response> [junit4] > request was:start=0&q=id:529&version=2.2&rows=20&qt=standard [junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716) [junit4] > ... 41 more [junit4] 2> 285795 T715 oas.SolrTestCaseJ4.setUp ###Starting testMaxDocs [junit4] 2> 285879 T715 C394 oass.SolrIndexSearcher.<init> Opening Searcher@8380dc34[collection1] realtime [junit4] 2> 285881 T715 C394 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1484275666506481664)} 0 85 [junit4] 2> 285886 T715 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 285887 T715 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> 285887 T715 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> 285888 T715 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> 285939 T715 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 285999 T715 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 286000 T715 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> 286005 T715 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 286282 T715 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 286286 T715 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 286288 T715 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 286293 T715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 286296 T715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 286299 T715 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 286300 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 286300 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 286300 T715 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 286301 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 286301 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 286302 T715 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> 286302 T715 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-AA7876B2A9F38E90-001/init-core-data-001/ [junit4] 2> 286302 T715 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@950e9771 [junit4] 2> 286303 T715 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-AA7876B2A9F38E90-001/init-core-data-001/index/ [junit4] 2> 286304 T715 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 286306 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 286306 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 286307 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 286307 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 286307 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 286308 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 286308 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 286308 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 286309 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 286309 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 286310 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 286310 T715 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 286310 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 286311 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 286311 T715 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 286312 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 286312 T715 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 286312 T715 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 286313 T715 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 286313 T715 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 286314 T715 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 286314 T715 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 286315 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 286315 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 286316 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 286316 T715 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 286316 T715 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 286317 T715 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 286317 T715 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 286318 T715 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 286318 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 286319 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 286319 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 286319 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 286320 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 286320 T715 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 286321 T715 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 286321 T715 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 286322 T715 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 286323 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 286324 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 286325 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 286326 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 286328 T715 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 286329 T715 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 286329 T715 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 286330 T715 oass.SolrIndexSearcher.<init> Opening Searcher@3ea90e7f[collection1] main [junit4] 2> 286331 T715 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 286331 T715 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 286331 T715 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 286332 T715 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 286332 T715 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 286332 T715 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 286334 T715 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 286342 T721 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ea90e7f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 286346 T715 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 286347 T715 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 286347 T715 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 286347 T715 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_1,generation=1} [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_2,generation=2} [junit4] 2> 286348 T715 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 286348 T715 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7039149154798221] [junit4] 2> 286350 T715 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_2,generation=2} [junit4] 2> 286350 T715 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 286351 T715 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 286351 T715 oass.SolrIndexSearcher.<init> Opening Searcher@29fecb18[collection1] main [junit4] 2> 286352 T715 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 286352 T715 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3d44e98 [junit4] 2> 286352 T715 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> 286353 T721 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29fecb18[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 286353 T715 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 286449 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[0 (1484275667104169984)]} 0 87 [junit4] 2> 286450 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[1 (1484275667105218560)]} 0 87 [junit4] 2> 286451 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[2 (1484275667105218561)]} 0 87 [junit4] 2> 286451 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[3 (1484275667106267136)]} 0 87 [junit4] 2> 286453 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[4 (1484275667107315712)]} 0 87 [junit4] 2> 286453 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[5 (1484275667108364288)]} 0 87 [junit4] 2> 286454 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[6 (1484275667109412864)]} 0 87 [junit4] 2> 286454 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[7 (1484275667109412865)]} 0 87 [junit4] 2> 286455 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[8 (1484275667110461440)]} 0 87 [junit4] 2> 286456 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[9 (1484275667111510016)]} 0 87 [junit4] 2> 286457 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[10 (1484275667112558592)]} 0 87 [junit4] 2> 286458 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[11 (1484275667113607168)]} 0 87 [junit4] 2> 286459 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[12 (1484275667114655744)]} 0 87 [junit4] 2> 286460 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[13 (1484275667115704320)]} 0 87 [junit4] 2> ASYNC NEW_CORE C395 name=collection1 org.apache.solr.core.SolrCore@fe356316 [junit4] 2> 286465 T715 C395 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:1&version=2.2&rows=20&qt=standard} hits=0 status=0 QTime=5 [junit4] 2> 286466 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[14 (1484275667121995776)]} 0 87 [junit4] 2> 286467 T722 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 286472 T722 oass.SolrIndexSearcher.<init> Opening Searcher@17ff4abd[collection1] main [junit4] 2> 286473 T722 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 286474 T721 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17ff4abd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C15)))} [junit4] 2> 286718 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[15 (1484275667385188352)]} 0 87 [junit4] 2> 286718 T715 C395 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:14&version=2.2&rows=20&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 286720 T715 C395 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:15&version=2.2&rows=20&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 286721 T715 oas.SolrTestCaseJ4.tearDown ###Ending testMaxDocs [junit4] 2> 286725 T715 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithin [junit4] 2> 286778 T715 C395 oass.SolrIndexSearcher.<init> Opening Searcher@12feb25d[collection1] realtime [junit4] 2> 286779 T715 C395 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1484275667393576960)} 0 54 [junit4] 2> 286780 T715 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 286781 T715 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> 286781 T715 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> 286782 T715 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> 286814 T715 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 286867 T715 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 286867 T715 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> 286872 T715 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 287142 T715 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 287146 T715 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 287149 T715 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 287154 T715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 287158 T715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 287163 T715 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 287164 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 287164 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 287165 T715 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 287166 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 287167 T715 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 287167 T715 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> 287168 T715 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-AA7876B2A9F38E90-001/init-core-data-001/ [junit4] 2> 287169 T715 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@950e9771 [junit4] 2> 287170 T715 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-AA7876B2A9F38E90-001/init-core-data-001/index/ [junit4] 2> 287171 T715 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 287174 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 287175 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 287175 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 287176 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 287176 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 287177 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 287177 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 287179 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 287180 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 287181 T715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 287181 T715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 287182 T715 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 287183 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 287184 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 287184 T715 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 287185 T715 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 287185 T715 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 287186 T715 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 287187 T715 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 287188 T715 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 287188 T715 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 287189 T715 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 287190 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 287190 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 287191 T715 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 287192 T715 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 287192 T715 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 287193 T715 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 287194 T715 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 287195 T715 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 287195 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 287196 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 287197 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 287198 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 287199 T715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 287199 T715 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 287200 T715 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 287201 T715 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 287202 T715 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 287204 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 287205 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 287207 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 287209 T715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 287211 T715 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 287212 T715 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 287213 T715 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 287214 T715 oass.SolrIndexSearcher.<init> Opening Searcher@8ac3cb7[collection1] main [junit4] 2> 287214 T715 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 287214 T715 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 287215 T715 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 287215 T715 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 287215 T715 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 287216 T715 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 287217 T715 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 287225 T723 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8ac3cb7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 287230 T715 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 287230 T715 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 287230 T715 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 287231 T715 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_2,generation=2} [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_3,generation=3} [junit4] 2> 287231 T715 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 287232 T715 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 287233 T715 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-227e5adf))),segFN=segments_3,generation=3} [junit4] 2> 287233 T715 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 287234 T715 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 287234 T715 oass.SolrIndexSearcher.<init> Opening Searcher@2b37f06d[collection1] main [junit4] 2> 287234 T715 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 287235 T715 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@fe356316 [junit4] 2> 287235 T715 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> 287235 T715 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 287236 T723 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2b37f06d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 287237 T715 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.CoreContainer.registerCore(CoreContainer.java:471) [junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:606) [junit4] 2> at org.apache.solr.update.AutoCommitTest.setUp(AutoCommitTest.java:135) [junit4] 2> at sun.reflect.GeneratedMethodAccessor16.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$7.evaluate(RandomizedRunner.java:861) [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> 287247 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1484275667939885056)]} 0 1 [junit4] 2> ASYNC NEW_CORE C396 name=collection1 org.apache.solr.core.SolrCore@7267f825 [junit4] 2> 287248 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:529&version=2.2&rows=20&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 291247 T724 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 291250 T724 oass.SolrIndexSearcher.<init> Opening Searcher@c872438e[collection1] main [junit4] 2> 291251 T724 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 291252 T723 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c872438e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):C1)))} [junit4] 2> 291501 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[530 (1484275672401575936)]} 0 1 [junit4] 2> 291502 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:530&version=2.2&rows=20&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 291503 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[529 (-1484275672403673088)]} 0 1 [junit4] 2> 291504 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:529&version=2.2&rows=20&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 292504 T724 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 292508 T724 oass.SolrIndexSearcher.<init> Opening Searcher@ef267407[collection1] main [junit4] 2> 292508 T724 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 292509 T723 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ef267407[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):C1)))} [junit4] 2> 292756 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:529&version=2.2&rows=20&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 292759 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:530&version=2.2&rows=20&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 292762 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673722781696)]} 0 1 [junit4] 2> 292763 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673724878848)]} 0 1 [junit4] 2> 292763 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673724878849)]} 0 1 [junit4] 2> 292764 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673725927424)]} 0 1 [junit4] 2> 292765 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673726976000)]} 0 1 [junit4] 2> 292766 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673728024576)]} 0 1 [junit4] 2> 292767 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673729073152)]} 0 1 [junit4] 2> 292768 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673730121728)]} 0 1 [junit4] 2> 292769 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673731170304)]} 0 1 [junit4] 2> 292770 T715 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1484275673732218880)]} 0 1 [junit4] 2> 292772 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:500&version=2.2&rows=20&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 292775 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673737461760)]} 0 1 [junit4] 2> 292776 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673738510336)]} 0 1 [junit4] 2> 292778 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673740607488)]} 0 1 [junit4] 2> 292779 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673741656064)]} 0 1 [junit4] 2> 292780 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673742704640)]} 0 1 [junit4] 2> 292780 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673742704641)]} 0 1 [junit4] 2> 292781 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673743753216)]} 0 1 [junit4] 2> 292782 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673744801792)]} 0 1 [junit4] 2> 292782 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673744801793)]} 0 1 [junit4] 2> 292783 T715 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1484275673745850368)]} 0 1 [junit4] 2> 292784 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:530&version=2.2&rows=20&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 293775 T724 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 293778 T724 oass.SolrIndexSearcher.<init> Opening Searcher@dc9b60e0[collection1] main [junit4] 2> 293779 T724 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 293780 T723 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dc9b60e0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.0.0):C10/9:delGen=1)))} [junit4] 2> 293786 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:500&version=2.2&rows=20&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 293788 T715 C396 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&q=id:530&version=2.2&rows=20&qt=standard} hits=0 status=0 QTime=1 [junit4] 2> 293789 T715 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithin [junit4] 2> 293791 T715 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> 293791 T715 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-1883073343 [junit4] 2> 293791 T715 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7267f825 [junit4] 2> 293791 T715 oasu.DirectUpdateHandler2.close closing DirectUpdateHand [...truncated too long message...] uate(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> 293805 T715 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> 293809 T715 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 293809 T715 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-AA7876B2A9F38E90-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-AA7876B2A9F38E90-001/init-core-data-001/index;done=false>>] [junit4] 2> 293809 T715 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-AA7876B2A9F38E90-001/init-core-data-001/index [junit4] 2> 293810 T715 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-AA7876B2A9F38E90-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-AA7876B2A9F38E90-001/init-core-data-001;done=false>>] [junit4] 2> 293810 T715 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-AA7876B2A9F38E90-001/init-core-data-001 [junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.update.AutoCommitTest-AA7876B2A9F38E90-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {multiDefault=PostingsFormat(name=Memory doPackFST= false), subject=PostingsFormat(name=Memory doPackFST= false), text=PostingsFormat(name=Direct), intDefault=Lucene50(blocksize=128), timestamp=PostingsFormat(name=Memory doPackFST= false), range_facet_l=PostingsFormat(name=Memory doPackFST= false), _version_=Lucene50(blocksize=128), id=FSTOrd50, field_t=FSTOrd50}, docValues:{timestamp=DocValuesFormat(name=Lucene50)}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=nl, timezone=America/Curacao [junit4] 2> NOTE: Linux 3.13.0-39-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=17118080,total=106037248 [junit4] 2> NOTE: All tests run in this JVM: [TestCollationField, QueryElevationComponentTest, TestFieldTypeCollectionResource, TestSchemaVersionResource, URLClassifyProcessorTest, FileBasedSpellCheckerTest, SolrCoreTest, TestZkChroot, AnalysisAfterCoreReloadTest, ResourceLoaderTest, TestSweetSpotSimilarityFactory, BasicZkTest, ScriptEngineTest, BasicDistributedZk2Test, TestRealTimeGet, HdfsBasicDistributedZkTest, TestWriterPerf, SolrRequestParserTest, CloudExitableDirectoryReaderTest, LeaderInitiatedRecoveryOnCommitTest, DirectSolrConnectionTest, DeleteReplicaTest, TestRecovery, ChaosMonkeySafeLeaderTest, TestJmxMonitoredMap, OverseerStatusTest, TestSolrXml, LegacyHTMLStripCharFilterTest, PreAnalyzedFieldTest, CoreAdminRequestStatusTest, ConnectionManagerTest, SpatialFilterTest, TestReversedWildcardFilterFactory, ZkSolrClientTest, TestExtendedDismaxParser, TestJoin, SchemaVersionSpecificBehaviorTest, TestFunctionQuery, QueryParsingTest, TestQueryUtils, TestInitParams, MigrateRouteKeyTest, DistributedTermsComponentTest, AutoCommitTest] [junit4] Completed on J1 in 11.84s, 3 tests, 1 error <<< FAILURES! [...truncated 1122 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:189: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:502: 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
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org