Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.10-Linux/17/ Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
3 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.core.TestCoreContainer Error Message: Suite timeout exceeded (>= 7200000 msec). Stack Trace: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). at __randomizedtesting.SeedInfo.seed([13CA3EC6FEEF059C]:0) REGRESSION: org.apache.solr.core.TestCoreContainer.testReloadThreaded Error Message: Test abandoned because suite timeout was reached. Stack Trace: java.lang.Exception: Test abandoned because suite timeout was reached. at __randomizedtesting.SeedInfo.seed([13CA3EC6FEEF059C]:0) REGRESSION: org.apache.solr.update.AutoCommitTest.testMaxTime Error Message: Exception during query Stack Trace: java.lang.RuntimeException: Exception during query at __randomizedtesting.SeedInfo.seed([13CA3EC6FEEF059C:893E4324607599A0]:0) at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:709) at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:227) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:94) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55) at java.lang.reflect.Method.invoke(Method.java:619) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:853) Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=0] xml response was: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1112</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><int name="range_facet_si">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="range_facet_sl"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1480539767729815552</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-09-29T01:05:50.902Z</date></doc></result> </response> request was:start=0&version=2.2&rows=20&q=id:529&qt=standard at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:702) ... 42 more Build Log: [...truncated 11415 lines...] [junit4] Suite: org.apache.solr.update.AutoCommitTest [junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001 [junit4] 2> 625725 T968 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (true) [junit4] 2> 625726 T968 oas.SolrTestCaseJ4.initCore ####initCore [junit4] 2> 625726 T968 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 625727 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 625727 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 625727 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 625767 T968 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.1 [junit4] 2> 625819 T968 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 625820 T968 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 625826 T968 oass.IndexSchema.readSchema [null] Schema name=test [junit4] 2> 626267 T968 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 626268 T968 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 626269 T968 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 626278 T968 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 626283 T968 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 626296 T968 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 626302 T968 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 626308 T968 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 626309 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 626309 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 626310 T968 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 626311 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 626312 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 626312 T968 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 626313 T968 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr [junit4] 2> 626313 T968 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/' [junit4] 2> 626383 T968 oasc.CoreContainer.<init> New CoreContainer 684312516 [junit4] 2> 626384 T968 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/] [junit4] 2> 626385 T968 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0 [junit4] 2> 626386 T968 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 626386 T968 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0 [junit4] 2> 626387 T968 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 626387 T968 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 626388 T968 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 626388 T968 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 626389 T968 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 626389 T968 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 626393 T968 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false [junit4] 2> 626396 T968 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 626397 T968 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 626397 T968 oasc.CoreContainer.load Host Name: [junit4] 2> 626402 T969 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 626402 T969 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 626403 T969 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 626403 T969 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 626443 T969 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.1 [junit4] 2> 626493 T969 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 626493 T969 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 626499 T969 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 626947 T969 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 626948 T969 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 626949 T969 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 626958 T969 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 626962 T969 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 626976 T969 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 626981 T969 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 626987 T969 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 626988 T969 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 626989 T969 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 626989 T969 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 626990 T969 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 626990 T969 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 626991 T969 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/ [junit4] 2> 626991 T969 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 626992 T969 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/ [junit4] 2> 626992 T969 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@80316e7a [junit4] 2> 626994 T969 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001 [junit4] 2> 626994 T969 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/index/ [junit4] 2> 626994 T969 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/index' doesn't exist. Creating new index... [junit4] 2> 626995 T969 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/index [junit4] 2> 626996 T969 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=8, maxMergeAtOnceExplicit=3, maxMergedSegmentMB=78.4560546875, floorSegmentMB=2.166015625, forceMergeDeletesPctAllowed=22.358211098778927, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3903939886826463 [junit4] 2> 626997 T969 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_1,generation=1} [junit4] 2> 626997 T969 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 626998 T969 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 627001 T969 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 627001 T969 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 627002 T969 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 627002 T969 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 627003 T969 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 627003 T969 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 627004 T969 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 627004 T969 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead [junit4] 2> 627005 T969 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params [junit4] 2> 627005 T969 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 627006 T969 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 627006 T969 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 627007 T969 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 627007 T969 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 627008 T969 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627008 T969 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627009 T969 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627010 T969 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627010 T969 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 627010 T969 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 627011 T969 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 627012 T969 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 627012 T969 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 627013 T969 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 627013 T969 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 627014 T969 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 627014 T969 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 627015 T969 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 627015 T969 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 627016 T969 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627016 T969 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 627017 T969 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627017 T969 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627018 T969 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627019 T969 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627019 T969 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627020 T969 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627020 T969 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627021 T969 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627021 T969 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 627022 T969 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 627024 T969 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627026 T969 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627028 T969 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627030 T969 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627032 T969 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627034 T969 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 627037 T969 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 627037 T969 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 627038 T969 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=1.9970703125, floorSegmentMB=1.62109375, forceMergeDeletesPctAllowed=13.248530551943729, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 627039 T969 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_1,generation=1} [junit4] 2> 627039 T969 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 627040 T969 oass.SolrIndexSearcher.<init> Opening Searcher@87b3d953[collection1] main [junit4] 2> 627040 T969 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 627041 T969 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 627041 T969 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 627042 T969 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 627042 T969 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 627042 T969 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 627109 T969 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 627136 T970 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@87b3d953[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 627142 T969 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 627144 T968 oas.SolrTestCaseJ4.initCore ####initCore end [junit4] 2> 627149 T968 oas.SolrTestCaseJ4.setUp ###Starting testMaxTime [junit4] 2> ASYNC NEW_CORE C732 name=collection1 org.apache.solr.core.SolrCore@4cfc7176 [junit4] 2> 627150 T968 C732 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1480539765954576384)} 0 1 [junit4] 2> 627155 T968 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 627157 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 627158 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 627158 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 627225 T968 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.1 [junit4] 2> 627304 T968 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 627306 T968 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 627315 T968 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 627826 T968 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 627827 T968 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 627828 T968 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 627838 T968 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 627842 T968 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 627855 T968 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 627861 T968 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 627867 T968 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 627868 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 627869 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 627870 T968 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 627871 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 627872 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 627872 T968 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/ [junit4] 2> 627873 T968 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/ [junit4] 2> 627874 T968 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@80316e7a [junit4] 2> 627875 T968 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/index/ [junit4] 2> 627875 T968 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 627880 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 627881 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 627882 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 627882 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 627883 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 627884 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 627884 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 627885 T968 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead [junit4] 2> 627886 T968 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params [junit4] 2> 627887 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 627887 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 627888 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 627889 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 627890 T968 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 627891 T968 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627892 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627893 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627894 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627894 T968 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 627895 T968 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 627896 T968 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 627897 T968 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 627898 T968 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 627899 T968 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 627900 T968 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 627901 T968 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 627901 T968 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 627902 T968 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 627903 T968 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 627904 T968 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 627904 T968 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 627905 T968 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627906 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627907 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627908 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627909 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627910 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627911 T968 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627912 T968 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 627913 T968 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 627914 T968 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 627916 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627918 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627920 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627922 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627925 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 627927 T968 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 627929 T968 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 627930 T968 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 627931 T968 oass.SolrIndexSearcher.<init> Opening Searcher@e24823c4[collection1] main [junit4] 2> 627932 T968 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 627933 T968 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 627934 T968 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 627934 T968 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 627935 T968 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 627936 T968 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 627938 T968 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 627949 T972 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e24823c4[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 627957 T968 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 627957 T968 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 627958 T968 oasu.DefaultSolrCoreState.closeIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 627959 T968 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=97.8828125, floorSegmentMB=1.4736328125, forceMergeDeletesPctAllowed=13.301891804302649, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.43719114896641487 [junit4] 2> 627960 T968 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_1,generation=1} [junit4] 2> 627961 T968 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 627961 T968 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 627962 T968 oass.SolrIndexSearcher.<init> Opening Searcher@72d8e618[collection1] main [junit4] 2> 627963 T968 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 627964 T968 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4cfc7176 [junit4] 2> 627964 T972 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@72d8e618[collection1] main{StandardDirectoryReader(segments_1:1:nrt)} [junit4] 2> 627972 T968 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> 627973 T968 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 628843 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1480539767729815552)]} 0 867 [junit4] 2> 629843 T973 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 629846 T973 oass.SolrIndexSearcher.<init> Opening Searcher@9683af1f[collection1] main [junit4] 2> 629848 T973 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 629849 T972 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9683af1f[collection1] main{StandardDirectoryReader(segments_1:3:nrt _0(4.10.1):C1)} [junit4] 2> ASYNC NEW_CORE C733 name=collection1 org.apache.solr.core.SolrCore@5672338d [junit4] 2> 629956 T968 C733 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:529&qt=standard} hits=1 status=0 QTime=1112 [junit4] 2> 629994 T968 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">1112</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><int name="range_facet_si">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="range_facet_sl"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1480539767729815552</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-09-29T01:05:50.902Z</date></doc></result> [junit4] 2> </response> [junit4] 2> [junit4] 2> request was:start=0&version=2.2&rows=20&q=id:529&qt=standard [junit4] 2> 629996 T968 oasc.SolrException.log ERROR REQUEST FAILED: start=0&version=2.2&rows=20&q=id:529&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">1112</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><int name="range_facet_si">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="range_facet_sl"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1480539767729815552</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-09-29T01:05:50.902Z</date></doc></result> [junit4] 2> </response> [junit4] 2> [junit4] 2> request was:start=0&version=2.2&rows=20&q=id:529&qt=standard [junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:702) [junit4] 2> at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:227) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:94) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:619) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) [junit4] 2> at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:853) [junit4] 2> [junit4] 2> 629997 T968 oas.SolrTestCaseJ4.tearDown ###Ending testMaxTime [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=13CA3EC6FEEF059C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sv_SE_PREEURO -Dtests.timezone=Africa/Khartoum -Dtests.file.encoding=UTF-8 [junit4] ERROR 2.87s J1 | AutoCommitTest.testMaxTime <<< [junit4] > Throwable #1: java.lang.RuntimeException: Exception during query [junit4] > at __randomizedtesting.SeedInfo.seed([13CA3EC6FEEF059C:893E4324607599A0]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:709) [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">1112</int></lst><result name="response" numFound="1" start="0"><doc><int name="id">529</int><int name="range_facet_si">529</int><arr name="range_facet_l"><long>529</long></arr><arr name="range_facet_sl"><long>529</long></arr><arr name="field_t"><str>what's inside?</str></arr><arr name="subject"><str>info</str></arr><long name="_version_">1480539767729815552</long><int name="intDefault">42</int><arr name="multiDefault"><str>muLti-Default</str></arr><date name="timestamp">2014-09-29T01:05:50.902Z</date></doc></result> [junit4] > </response> [junit4] > request was:start=0&version=2.2&rows=20&q=id:529&qt=standard [junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:702) [junit4] > ... 42 more [junit4] 2> 630018 T968 oas.SolrTestCaseJ4.setUp ###Starting testMaxDocs [junit4] 2> 630019 T968 C733 oass.SolrIndexSearcher.<init> Opening Searcher@12a7e00e[collection1] realtime [junit4] 2> 630020 T968 C733 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1480539768962940928)} 0 2 [junit4] 2> 630021 T968 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 630022 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 630022 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 630023 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 630070 T968 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.1 [junit4] 2> 630121 T968 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 630122 T968 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 630128 T968 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 630451 T968 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 630452 T968 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 630453 T968 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 630459 T968 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 630462 T968 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 630471 T968 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 630475 T968 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 630480 T968 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 630481 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 630482 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 630482 T968 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 630483 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 630483 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 630484 T968 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/ [junit4] 2> 630484 T968 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/ [junit4] 2> 630485 T968 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@80316e7a [junit4] 2> 630486 T968 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/index/ [junit4] 2> 630486 T968 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 630489 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 630489 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 630490 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 630490 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 630491 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 630491 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 630491 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 630492 T968 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead [junit4] 2> 630492 T968 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params [junit4] 2> 630493 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 630493 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 630494 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 630494 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 630495 T968 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 630495 T968 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 630496 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 630497 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 630497 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 630498 T968 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 630498 T968 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 630499 T968 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 630499 T968 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 630500 T968 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 630500 T968 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 630501 T968 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 630501 T968 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 630502 T968 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 630502 T968 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 630503 T968 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 630503 T968 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 630504 T968 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 630504 T968 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 630505 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 630505 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 630506 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 630507 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 630507 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 630508 T968 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 630508 T968 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 630509 T968 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 630510 T968 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 630511 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630512 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630514 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630515 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630516 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 630518 T968 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 630519 T968 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 630520 T968 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 630521 T968 oass.SolrIndexSearcher.<init> Opening Searcher@45f425e3[collection1] main [junit4] 2> 630521 T968 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 630522 T968 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 630522 T968 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 630523 T968 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 630523 T968 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 630523 T968 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 630525 T968 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 630533 T974 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@45f425e3[collection1] main{StandardDirectoryReader(segments_1:5:nrt)} [junit4] 2> 630538 T968 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 630539 T968 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 630539 T968 oasu.DefaultSolrCoreState.closeIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 630540 T968 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_1,generation=1} [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_2,generation=2} [junit4] 2> 630540 T968 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 630541 T968 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=368333187, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 630543 T968 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_2,generation=2} [junit4] 2> 630543 T968 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 630544 T968 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 630544 T968 oass.SolrIndexSearcher.<init> Opening Searcher@e71084e6[collection1] main [junit4] 2> 630545 T968 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 630545 T968 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5672338d [junit4] 2> 630546 T974 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e71084e6[collection1] main{StandardDirectoryReader(segments_2:5:nrt)} [junit4] 2> 630550 T968 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> 630551 T968 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 630553 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[0 (1480539769523929088)]} 0 0 [junit4] 2> 630554 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[1 (1480539769524977664)]} 0 0 [junit4] 2> 630555 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[2 (1480539769524977665)]} 0 0 [junit4] 2> 630555 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[3 (1480539769526026240)]} 0 0 [junit4] 2> 630556 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[4 (1480539769527074816)]} 0 0 [junit4] 2> 630556 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[5 (1480539769527074817)]} 0 0 [junit4] 2> 630557 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[6 (1480539769528123392)]} 0 0 [junit4] 2> 630557 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[7 (1480539769528123393)]} 0 0 [junit4] 2> 630558 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[8 (1480539769529171968)]} 0 0 [junit4] 2> 630559 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[9 (1480539769529171969)]} 0 0 [junit4] 2> 630559 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[10 (1480539769530220544)]} 0 0 [junit4] 2> 630560 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[11 (1480539769530220545)]} 0 0 [junit4] 2> 630560 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[12 (1480539769531269120)]} 0 0 [junit4] 2> 630561 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[13 (1480539769532317696)]} 0 0 [junit4] 2> ASYNC NEW_CORE C734 name=collection1 org.apache.solr.core.SolrCore@81b62d6d [junit4] 2> 630569 T968 C734 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:1&qt=standard} hits=0 status=0 QTime=8 [junit4] 2> 630586 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[14 (1480539769558532096)]} 0 0 [junit4] 2> 630587 T975 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 630590 T975 oass.SolrIndexSearcher.<init> Opening Searcher@9d9581d8[collection1] main [junit4] 2> 630590 T975 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 630591 T974 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9d9581d8[collection1] main{StandardDirectoryReader(segments_2:7:nrt _1(4.10.1):C15)} [junit4] 2> 630838 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[15 (1480539769821724672)]} 0 0 [junit4] 2> 630839 T968 C734 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:14&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 630841 T968 C734 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:15&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 630843 T968 oas.SolrTestCaseJ4.tearDown ###Ending testMaxDocs [junit4] 2> 630850 T968 oas.SolrTestCaseJ4.setUp ###Starting testCommitWithin [junit4] 2> 630856 T968 C734 oass.SolrIndexSearcher.<init> Opening Searcher@d09b9df6[collection1] realtime [junit4] 2> 630857 T968 C734 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1480539769836404736)} 0 7 [junit4] 2> 630859 T968 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 630860 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 630861 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 630862 T968 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 630920 T968 oasc.SolrConfig.<init> Using Lucene MatchVersion: 4.10.1 [junit4] 2> 630972 T968 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 630973 T968 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 630979 T968 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 631310 T968 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 631311 T968 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead. [junit4] 2> 631312 T968 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 631318 T968 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 631321 T968 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 631330 T968 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 631334 T968 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 631338 T968 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 631339 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 631339 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 631340 T968 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 631340 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 631341 T968 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 631341 T968 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/ [junit4] 2> 631342 T968 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/ [junit4] 2> 631342 T968 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@80316e7a [junit4] 2> 631343 T968 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001/index/ [junit4] 2> 631344 T968 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter [junit4] 2> 631346 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 631347 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 631347 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 631348 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 631348 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 631349 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 631349 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 631349 T968 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead [junit4] 2> 631350 T968 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params [junit4] 2> 631350 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 631351 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 631351 T968 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 631352 T968 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 631352 T968 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 631353 T968 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 631354 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 631354 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 631355 T968 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 631355 T968 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 631356 T968 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 631356 T968 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 631357 T968 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 631358 T968 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler [junit4] 2> 631358 T968 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler [junit4] 2> 631359 T968 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 631359 T968 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 631360 T968 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler [junit4] 2> 631360 T968 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler [junit4] 2> 631361 T968 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler [junit4] 2> 631361 T968 oasc.RequestHandlers.initHandlersFromConfig WARN Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 631362 T968 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 631362 T968 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler [junit4] 2> 631363 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler [junit4] 2> 631364 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 631364 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler [junit4] 2> 631365 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler [junit4] 2> 631365 T968 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler [junit4] 2> 631366 T968 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 631367 T968 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler [junit4] 2> 631367 T968 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler [junit4] 2> 631368 T968 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler [junit4] 2> 631369 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 631371 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 631372 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 631373 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 631374 T968 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 631376 T968 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 631378 T968 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 631378 T968 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 631379 T968 oass.SolrIndexSearcher.<init> Opening Searcher@2b06e025[collection1] main [junit4] 2> 631379 T968 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 631380 T968 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 631380 T968 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed [junit4] 2> 631381 T968 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage [junit4] 2> 631381 T968 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 631382 T968 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 631383 T968 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 631390 T976 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2b06e025[collection1] main{StandardDirectoryReader(segments_2:11:nrt)} [junit4] 2> 631396 T968 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 631396 T968 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 631397 T968 oasu.DefaultSolrCoreState.closeIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 631397 T968 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_2,generation=2} [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_3,generation=3} [junit4] 2> 631398 T968 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 631398 T968 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 631399 T968 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_3,generation=3} [junit4] 2> 631400 T968 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 631400 T968 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 631401 T968 oass.SolrIndexSearcher.<init> Opening Searcher@76fb5089[collection1] main [junit4] 2> 631401 T968 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 631402 T968 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@81b62d6d [junit4] 2> 631403 T976 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@76fb5089[collection1] main{StandardDirectoryReader(segments_3:11:nrt)} [junit4] 2> 631407 T968 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> 631408 T968 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 631410 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[529 (1480539770421510144)]} 0 1 [junit4] 2> ASYNC NEW_CORE C735 name=collection1 org.apache.solr.core.SolrCore@9a1e04d [junit4] 2> 631411 T968 C735 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:529&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 635410 T977 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 635413 T977 oass.SolrIndexSearcher.<init> Opening Searcher@e231fe6b[collection1] main [junit4] 2> 635413 T977 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 635414 T976 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e231fe6b[collection1] main{StandardDirectoryReader(segments_3:13:nrt _3(4.10.1):C1)} [junit4] 2> 635415 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[530 (1480539774622105600)]} 0 1 [junit4] 2> 635416 T968 C735 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:530&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 635418 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[529 (-1480539774624202752)]} 0 1 [junit4] 2> 635418 T968 C735 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:529&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 636418 T977 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 636420 T977 oass.SolrIndexSearcher.<init> Opening Searcher@f740fece[collection1] main [junit4] 2> 636421 T977 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 636424 T976 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f740fece[collection1] main{StandardDirectoryReader(segments_3:17:nrt _4(4.10.1):C1)} [junit4] 2> 636670 T968 C735 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:529&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 636671 T968 C735 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:530&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 636673 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775941214208)]} 0 1 [junit4] 2> 636674 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775942262784)]} 0 1 [junit4] 2> 636675 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775942262785)]} 0 1 [junit4] 2> 636675 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775943311360)]} 0 1 [junit4] 2> 636676 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775943311361)]} 0 1 [junit4] 2> 636676 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775944359936)]} 0 1 [junit4] 2> 636677 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775945408512)]} 0 1 [junit4] 2> 636677 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775945408513)]} 0 1 [junit4] 2> 636678 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775946457088)]} 0 1 [junit4] 2> 636678 T968 oasup.LogUpdateProcessor.finish [collection1] {add=[500 (1480539775946457089)]} 0 1 [junit4] 2> 636679 T968 C735 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:500&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 636680 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775948554240)]} 0 1 [junit4] 2> 636681 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775949602816)]} 0 1 [junit4] 2> 636681 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775949602817)]} 0 1 [junit4] 2> 636682 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775950651392)]} 0 1 [junit4] 2> 636682 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775950651393)]} 0 1 [junit4] 2> 636683 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775951699968)]} 0 1 [junit4] 2> 636683 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775951699969)]} 0 1 [junit4] 2> 636684 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775952748544)]} 0 1 [junit4] 2> 636684 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775952748545)]} 0 1 [junit4] 2> 636685 T968 oasup.LogUpdateProcessor.finish [collection1] {delete=[530 (-1480539775952748546)]} 0 1 [junit4] 2> 636685 T968 C735 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:530&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 637680 T977 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 637690 T977 oass.SolrIndexSearcher.<init> Opening Searcher@7c06bfcd[collection1] main [junit4] 2> 637691 T977 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 637692 T976 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7c06bfcd[collection1] main{StandardDirectoryReader(segments_3:21:nrt _5(4.10.1):C10/9:delGen=1)} [junit4] 2> ASYNC NEW_CORE C736 name=collection1 org.apache.solr.core.SolrCore@9a1e04d [junit4] 2> 637939 T968 C736 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:500&qt=standard} hits=1 status=0 QTime=0 [junit4] 2> 637941 T968 C736 oasc.SolrCore.execute [collection1] webapp=null path=null params={start=0&version=2.2&rows=20&q=id:530&qt=standard} hits=0 status=0 QTime=0 [junit4] 2> 637942 T968 oas.SolrTestCaseJ4.tearDown ###Ending testCommitWithin [junit4] 2> 637944 T968 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> 637945 T968 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=684312516 [junit4] 2> 637945 T968 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@9a1e04d [junit4] 2> 637955 T968 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=3,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=12,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=12,cumulative_deletesById=11,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 637955 T968 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState [junit4] 2> 637955 T968 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 637956 T968 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser [junit4] 2> 637956 T968 C736 oasu.DirectUpdateHandler2.closeWriter Committing on IndexWriter close. [junit4] 2> 637957 T968 C736 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_3,generation=3} [junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@196b007e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b339369)),segFN=segments_4,generation=4} [junit4] 2> 637957 T968 C736 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4 [junit4] 2> 637958 T968 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 637959 T968 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 637959 T968 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J1/./solr.update.AutoCommitTest-13CA3EC6FEEF059C-001/init-core-data-001;done=false>>] [junit4] 2> 637959 T968 oasc.CachingDirectoryFactory.clo [...truncated too long message...] tory.getParameter Setting socketTimeout to: 0 [junit4] 2> 9540290 T5614 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 9540290 T5614 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0 [junit4] 2> 9540291 T5614 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 9540291 T5614 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 9540292 T5614 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 9540292 T5614 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 9540292 T5614 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 9540293 T5614 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 9540295 T5614 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=0&connTimeout=0&retry=false [junit4] 2> 9540297 T5614 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 9540297 T5614 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 9540298 T5614 oasc.CoreContainer.load Host Name: null [junit4] 2> 9540300 T5614 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=72931010 [junit4] 2> 9540301 T5614 oas.SolrTestCaseJ4.tearDown ###Ending testDeleteBadCores [junit4] 2> 9540306 T5614 oas.SolrTestCaseJ4.setUp ###Starting testReloadSequential [junit4] 2> 9540454 T5614 oas.SolrTestCaseJ4.tearDown ###Ending testReloadSequential [junit4] 2> 9540459 T5614 oas.SolrTestCaseJ4.setUp ###Starting testLogWatcherEnabledByDefault [junit4] 2> 9540459 T5614 oas.SolrTestCaseJ4.tearDown ###Ending testLogWatcherEnabledByDefault [junit4] 2> 9540461 T5614 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> 9540462 T5614 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1876401908 [junit4] 2> 9540462 T5614 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@62382d2f [junit4] 2> 9540463 T5614 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=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 9540464 T5614 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState [junit4] 2> 9540464 T5614 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 9540464 T5614 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser [junit4] 2> 9540465 T5614 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 9540465 T5614 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 9540466 T5614 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J0/./solr.core.TestCoreContainer-13CA3EC6FEEF059C-001/init-core-data-001 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J0/./solr.core.TestCoreContainer-13CA3EC6FEEF059C-001/init-core-data-001;done=false>>] [junit4] 2> 9540466 T5614 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J0/./solr.core.TestCoreContainer-13CA3EC6FEEF059C-001/init-core-data-001 [junit4] 2> 9540467 T5614 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J0/./solr.core.TestCoreContainer-13CA3EC6FEEF059C-001/init-core-data-001/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J0/./solr.core.TestCoreContainer-13CA3EC6FEEF059C-001/init-core-data-001/index;done=false>>] [junit4] 2> 9540467 T5614 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J0/./solr.core.TestCoreContainer-13CA3EC6FEEF059C-001/init-core-data-001/index [junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build/solr-core/test/J0/./solr.core.TestCoreContainer-13CA3EC6FEEF059C-001 [junit4] 2> 7220596 T5613 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=Asserting(Lucene410), sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=de_CH, timezone=Atlantic/Faroe [junit4] 2> NOTE: Linux 3.13.0-36-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=1,free=165326488,total=305725440 [junit4] 2> NOTE: All tests run in this JVM: [TestPartialUpdateDeduplication, SolrCoreCheckLockOnStartupTest, FileUtilsTest, DistributedIntervalFacetingTest, TestSolrCoreProperties, TestStressVersions, TestAnalyzedSuggestions, TestBM25SimilarityFactory, TestFaceting, TestBlendedInfixSuggestions, NotRequiredUniqueKeyTest, LeaderElectionTest, SolrIndexSplitterTest, DocumentBuilderTest, RankQueryTest, DocValuesMissingTest, TestSchemaSimilarityResource, TestQuerySenderListener, FieldMutatingUpdateProcessorTest, TestLuceneMatchVersion, JsonLoaderTest, MBeansHandlerTest, TestSolrJ, DocumentAnalysisRequestHandlerTest, HighlighterMaxOffsetTest, DocValuesMultiTest, ZkNodePropsTest, TestCopyFieldCollectionResource, TestComponentsName, TestLMJelinekMercerSimilarityFactory, TestDynamicFieldResource, TestTolerantSearch, DocExpirationUpdateProcessorFactoryTest, TestSolr4Spatial, MultiThreadedOCPTest, PathHierarchyTokenizerFactoryTest, TestConfig, TestComplexPhraseQParserPlugin, JSONWriterTest, TestCloudPivotFacet, TestExceedMaxTermLength, TestBinaryResponseWriter, TestCursorMarkWithoutUniqueKey, HighlighterTest, TestSort, AliasIntegrationTest, ReplicationFactorTest, TestRangeQuery, CursorPagingTest, SpellCheckCollatorTest, BlockDirectoryTest, DistribCursorPagingTest, DirectUpdateHandlerTest, TestSolrIndexConfig, TestChildDocTransformer, TestSchemaVersionResource, BasicDistributedZkTest, TestSolr4Spatial2, TestRTGBase, TestCodecSupport, ShowFileRequestHandlerTest, TestSuggestSpellingConverter, DistributedQueryComponentOptimizationTest, XsltUpdateRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestCoreDiscovery, IndexBasedSpellCheckerTest, ExternalFileFieldSortTest, TestDynamicFieldCollectionResource, UnloadDistributedZkTest, TestAddFieldRealTimeGet, TestDistributedSearch, FastVectorHighlighterTest, TestManagedSynonymFilterFactory, TestSearchPerf, TestManagedResourceStorage, ChangedSchemaMergeTest, TestDefaultSimilarityFactory, TestFoldingMultitermQuery, TestStressRecovery, TestDocBasedVersionConstraints, TestSerializedLuceneMatchVersion, SuggestComponentTest, SignatureUpdateProcessorFactoryTest, DirectSolrSpellCheckerTest, InfoHandlerTest, XmlUpdateRequestHandlerTest, TestCloudInspectUtil, AnalyticsMergeStrategyTest, ShardRoutingTest, SimpleFacetsTest, TestNumberUtils, ResponseLogComponentTest, DistributedSuggestComponentTest, ParsingFieldUpdateProcessorsTest, ClusterStateUpdateTest, MinimalSchemaTest, TestFastOutputStream, StandardRequestHandlerTest, SpellingQueryConverterTest, TestArbitraryIndexDir, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, SyncSliceTest, OpenCloseCoreStressTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler, TestRecovery, TestHashPartitioner, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestCoreContainer] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCoreContainer -Dtests.seed=13CA3EC6FEEF059C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de_CH -Dtests.timezone=Atlantic/Faroe -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | TestCoreContainer (suite) <<< [junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). [junit4] > at __randomizedtesting.SeedInfo.seed([13CA3EC6FEEF059C]:0) [junit4] Completed on J0 in 7220.63s, 3 tests, 2 errors <<< FAILURES! [...truncated 12 lines...] BUILD FAILED /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/build.xml:474: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/build.xml:467: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/build.xml:45: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/extra-targets.xml:37: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/build.xml:189: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/solr/common-build.xml:494: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/lucene/common-build.xml:1351: The following error occurred while executing this line: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.10-Linux/lucene/common-build.xml:966: There were test failures: 419 suites, 1731 tests, 1 suite-level error, 2 errors, 47 ignored (18 assumptions) Total time: 215 minutes 41 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;} Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org