You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/08/06 04:50:39 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b99) - Build #
6894 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/6894/
Java: 32bit/jdk1.8.0-ea-b99 -client -XX:+UseG1GC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.update.DirectUpdateHandlerTest
Error Message:
ERROR: SolrIndexSearcher opens=22 closes=21
Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=22 closes=21
at __randomizedtesting.SeedInfo.seed([EB69FED2611887A7]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:326)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:132)
at sun.reflect.GeneratedMethodAccessor25.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:724)
REGRESSION: org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([EB69FED2611887A7:C710BA5714A14F02]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes(DirectUpdateHandlerTest.java:280)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9821 lines...]
[junit4] Suite: org.apache.solr.update.DirectUpdateHandlerTest
[junit4] 2> 418299 T1422 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-DirectUpdateHandlerTest-1375756689761
[junit4] 2> 418300 T1422 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 418316 T1422 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 418317 T1422 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 418350 T1422 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 418375 T1422 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 418375 T1422 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 418381 T1422 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 418541 T1422 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 418542 T1422 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 418543 T1422 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 418548 T1422 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 418550 T1422 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 418552 T1422 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 418552 T1422 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 418552 T1422 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 418563 T1422 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 418563 T1422 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 418564 T1422 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 418581 T1422 oasc.CoreContainer.<init> New CoreContainer 21898349
[junit4] 2> 418581 T1422 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 418582 T1422 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 418582 T1422 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 418582 T1422 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 418583 T1422 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 418583 T1422 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 418583 T1422 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 418583 T1422 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 418584 T1422 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 418584 T1422 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 418584 T1422 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 418587 T1422 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 418589 T1423 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 418589 T1423 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 418590 T1423 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 418590 T1423 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 418618 T1423 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 418642 T1423 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 418642 T1423 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 418648 T1423 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 418789 T1423 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 418791 T1423 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 418792 T1423 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 418796 T1423 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 418798 T1423 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 418799 T1423 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockFSDirectoryFactory
[junit4] 2> 418800 T1423 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-DirectUpdateHandlerTest-1375756689761/
[junit4] 2> 418800 T1423 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d0a4c
[junit4] 2> 418801 T1423 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761
[junit4] 2> 418801 T1423 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-DirectUpdateHandlerTest-1375756689761/index/
[junit4] 2> 418801 T1423 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-DirectUpdateHandlerTest-1375756689761/index' doesn't exist. Creating new index...
[junit4] 2> 418802 T1423 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index
[junit4] 2> 418803 T1423 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_1,generation=1}
[junit4] 2> 418803 T1423 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 418803 T1423 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 418806 T1423 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 418806 T1423 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 418807 T1423 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 418807 T1423 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 418807 T1423 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 418808 T1423 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 418808 T1423 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 418808 T1423 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 418809 T1423 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 418810 T1423 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 418810 T1423 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 418810 T1423 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 418811 T1423 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 418811 T1423 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 418811 T1423 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 418812 T1423 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 418812 T1423 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 418813 T1423 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 418813 T1423 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 418814 T1423 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 418814 T1423 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 418814 T1423 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 418815 T1423 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 418815 T1423 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 418816 T1423 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 418816 T1423 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 418818 T1423 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 418819 T1423 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 418820 T1423 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, SOLRCONFIG-HIGHLIGHT.XML, SCHEMA-REQUIRED-FIELDS.XML, SCHEMA-REPLICATION2.XML, TRIVIAL.UPDATEPROCESSOR1.JS, SCHEMA-MINIMAL.XML, BAD-SCHEMA-DOCVALUES-NOT-REQUIRED-NO-DEFAULT.XML, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, SOLRCONFIG-CACHING.XML, BAD-SOLRCONFIG-UNEXPECTED-SCHEMA-ATTRIBUTE.XML, SCHEMA-POSTINGSHIGHLIGHT.XML, SOLRCONFIG-REPEATER.XML, REGEX-BOOST-PROCESSOR-TEST.TXT, SOLRCONFIG-MERGEPOLICY.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, BAD-SCHEMA-CURRENCY-FT-BOGUS-CODE-IN-XML.XML, CURRENCY.XML, SOLRCONFIG-TLOG.XML, SOLRCONFIG-MASTER.XML, SOLRCONFIG-COMPONENTS-NAME.XML, SOLRCONFIG-MERGEPOLICY-LEGACY.XML, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, SCHEMA11.XML, SOLRCONFIG-BASIC.XML, DA_COMPOUNDDICTIONARY.TXT, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SCHEMA-COPYFIELD-TEST.XML, SOLRCONFIG-SLAVE.XML, ELEVATE.XML, TRIVIAL.UPDATEPROCESSOR0.JS, SCHEMA-SWEETSPOT.XML, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML, SCHEMA-IB.XML, SCHEMA-CHARFILTERS.XML, BAD-SCHEMA-MISPLACED-ASTERISK-COPYFIELD-SOURCE-SHOULD-FAIL-TEST.XML, SOLRCONFIG-QUERYSENDER.XML, SCHEMA-REPLICATION1.XML, SOLRCONFIG-SNIPPET-PROCESSOR.XML, DA_UTF8.XML, BAD-SOLRCONFIG-MULTIPLE-INDEXCONFIGS.XML, BAD-SCHEMA-NON-GLOB-COPYFIELD-SOURCE-MATCHING-NOTHING-SHOULD-FAIL-TEST.XML, SCHEMA-TINY.XML, CONDITIONAL.UPDATEPROCESSOR.JS, SOLRCONFIG-DEFAULTS.XML, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, SOLRCONFIG-ENABLEPLUGIN.XML, HYPHENATION.DTD, BAD-SOLRCONFIG-SCHEMA-MUTABLE-BUT-NOT-MANAGED.XML, STEMDICT.TXT, SCHEMA-PHRASESUGGEST.XML, SCHEMA-ID-AND-VERSION-FIELDS-ONLY.XML, HUNSPELL-TEST.AFF, SCHEMA-ONE-FIELD-NO-DYNAMIC-FIELD-UNIQUE-KEY.XML, BAD-SCHEMA-CURRENCY-FT-OER-NORATES.XML, SCHEMA-SNIPPET-TYPE.XML, STOPTYPES-1.TXT, STOPWORDSWRONGENCODING.TXT, SCHEMA-NUMERIC.XML, SOLRCONFIG-TRANSFORMERS.XML, BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, SOLRCONFIG-SIMPLELOCK.XML, SCHEMA-DOCVALUES.XML, SOLRCONFIG-TLOG-MUTABLE-MANAGED-SCHEMA.XML, SOLRCONFIG-INFOSTREAM-LOGGING.XML, WDFTYPES.TXT, SCHEMA-REVERSED.XML, STOPTYPES-2.TXT, BAD-SCHEMA-CURRENCY-FT-MULTIVALUED.XML, BAD-SCHEMA-BOGUS-ANALYSIS-PARAMETERS.XML, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, BAD-SCHEMA-CURRENCY-MULTIVALUED.XML, SOLRCONFIG-PHRASESUGGEST.XML, SCHEMA-DFR.XML, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, KEEP-1.TXT, SCHEMA-SPATIAL.XML, OPEN-EXCHANGE-RATES.JSON, STOPWITHBOM.TXT, SCHEMA-BINARYFIELD.XML, SOLRCONFIG-SPELLCHECKER.XML, SOLRCONFIG-POSTINGSHIGHLIGHT.XML, SOLRCONFIG-WARMER.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, BAD-SCHEMA-DUP-FIELDTYPE.XML, SCHEMA-XINCLUDE.XML, SOLRCONFIG-MASTER1.XML, BAD-SCHEMA-MULTIPLE-ASTERISK-COPYFIELD-SOURCE-SHOULD-FAIL-TEST.XML, SCHEMA-BEHAVIOR.XML, SYNONYMS.TXT, SOLRCONFIG-MINIMAL.XML, SCHEMA-DOCVALUESMULTI.XML, BAD-SCHEMA-CURRENCY-DYNAMIC-MULTIVALUED.XML, BAD-SCHEMA-UNSUPPORTED-DOCVALUES.XML, SCHEMA_CODEC.XML, SCHEMA-SNIPPET-TYPES.INCL, BAD-SCHEMA-SWEETSPOT-PARTIAL-HYPERBOLIC.XML, SOLRCONFIG-SOLR-749.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, SCHEMA-EFF.XML, SOLRCONFIG-FUNCTIONQUERY.XML, STOP-2.TXT, SCHEMA-LMDIRICHLET.XML, SOLRCONFIG-TERMINDEX.XML, SCHEMA-ONE-FIELD-NO-DYNAMIC-FIELD.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-NORMS.XML, SOLRCONFIG-ELEVATE.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-BASELINE.XML, STOPWORDS.TXT, SCHEMA-FOLDING.XML, SCHEMA-STOP-KEEP.XML, BAD-SCHEMA-BOGUS-FIELD-PARAMETERS.XML, FUZZYSUGGEST.TXT, BAD-SOLRCONFIG-MANAGED-SCHEMA-NAMED-SCHEMA.XML.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, SCHEMA-REST.XML, SOLRCONFIG-TLOG-MANAGED-SCHEMA.XML, SOLRCONFIG-PARSING-UPDATE-PROCESSOR-CHAINS.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, STOP-1.TXT, SOLRCONFIG-MANAGED-SCHEMA.XML, SOLRCONFIG-MASTER2.XML, SOLRCONFIG-MERGEPOLICY-DEFAULTS.XML, SCHEMA-SPELLCHECKER.XML, SOLRCONFIG-RESPONSE-LOG-COMPONENT.XML, BAD-SCHEMA-MISPLACED-ASTERISK-COPYFIELD-DEST-SHOULD-FAIL-TEST.XML, SOLRCONFIG-LAZYWRITER.XML, SCHEMA-LUCENEMATCHVERSION.XML, SCHEMA-CLASS-NAME-SHORTENING-ON-SERIALIZATION.XML, FRENCHARTICLES.TXT, BAD-MP-SOLRCONFIG.XML, SCHEMA15.XML, SOLRCONFIG-REQHANDLER.INCL, SCHEMASURROUND.XML, SOLRCONFIG-MASTER3.XML, HUNSPELL-TEST.DIC, BAD-SCHEMA-MULTIPLE-ASTERISK-COPYFIELD-DEST-SHOULD-FAIL-TEST.XML, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-XINCLUDE.XML, SOLRCONFIG-SLAVE1.XML, SOLRCONFIG-DELPOLICY1.XML, SCHEMA-SIM.XML, SCHEMA-SNIPPET-FIELD.XML, SCHEMA-COLLATE.XML, STOP-SNOWBALL.TXT, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, PROTWORDS.TXT, SOLRCONFIG-ADD-SCHEMA-FIELDS-UPDATE-PROCESSOR-CHAINS.XML, SCHEMA-TRIE.XML, SOLRCONFIG_CODEC.XML, SCHEMA-SYNONYM-TOKENIZER.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, JASUGGEST.TXT, SCHEMA-REST-LUCENE-MATCH-VERSION.XML, BAD-SOLRCONFIG-MULTIPLE-CFS.XML, SCHEMA-DOCVALUESFACETING.XML, SCHEMA-TFIDF.XML, SCHEMA-ADD-SCHEMA-FIELDS-UPDATE-PROCESSOR.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, SCHEMA-LMJELINEKMERCER.XML, PHRASESUGGEST.TXT, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, OLD_SYNONYMS.TXT, SOLRCONFIG-DELPOLICY2.XML, XSLT, SOLRCONFIG-NATIVELOCK.XML, BAD-SCHEMA-DUP-FIELD.XML, SOLRCONFIG-MUTABLE-MANAGED-SCHEMA.XML, SOLRCONFIG-NOCACHE.XML, SCHEMA-BM25.XML, BAD-SCHEMA-SWEETSPOT-BOTH-TF.XML, ADDFIELDS.UPDATEPROCESSOR.JS, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, SOLRCONFIG-ALTDIRECTORY.XML, COMPOUNDDICTIONARY.TXT, SOLRCONFIG_PERF.XML, BAD-SCHEMA-CURRENCY-FT-BOGUS-DEFAULT-CODE.XML, BAD-CURRENCY.XML, SOLRCONFIG-INDEXCONFIG.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, KEEP-2.TXT, SCHEMA12.XML, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, MAPPING-ISOLATIN1ACCENT.TXT, BAD_SOLRCONFIG.XML, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML]
[junit4] 2> 418823 T1423 oass.SolrIndexSearcher.<init> Opening Searcher@164215c main
[junit4] 2> 418823 T1423 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 418823 T1423 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 418824 T1423 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 418869 T1423 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 418888 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@164215c main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 418894 T1423 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 418897 T1422 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 418900 T1422 oas.SolrTestCaseJ4.setUp ###Starting testRequireUniqueKey
[junit4] 2> ASYNC NEW_CORE C2829 name=collection1 org.apache.solr.core.SolrCore@f104d5
[junit4] 2> 418903 T1422 C2829 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 418905 T1422 C2829 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_1,generation=1}
[junit4] 2> 418906 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 418906 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 3
[junit4] 2> 418911 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 418912 T1422 C2829 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_2,generation=2}
[junit4] 2> 418913 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 418913 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@16e9dce main
[junit4] 2> 418914 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 418916 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16e9dce main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 418917 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 418921 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 1
[junit4] 2> 418924 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {} 0 0
[junit4] 2> 418925 T1422 C2829 oasc.SolrException.log Ignoring exception matching ignore_exception
[junit4] 2> 418928 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {} 0 0
[junit4] 2> 418929 T1422 C2829 oasc.SolrException.log Ignoring exception matching id
[junit4] 2> 418929 T1422 oas.SolrTestCaseJ4.tearDown ###Ending testRequireUniqueKey
[junit4] 2> 418933 T1422 oas.SolrTestCaseJ4.setUp ###Starting testExpungeDeletes
[junit4] 2> 418936 T1422 C2829 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 418937 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 418940 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 418941 T1422 C2829 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_3,generation=3}
[junit4] 2> 418941 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 418942 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@1de2486 main
[junit4] 2> 418942 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 418944 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1de2486 main{StandardDirectoryReader(segments_3:4:nrt)}
[junit4] 2> 418945 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 418949 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 1
[junit4] 2> 418952 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 0
[junit4] 2> 418955 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 418958 T1422 C2829 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_4,generation=4}
[junit4] 2> 418959 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 418962 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@11c568b main
[junit4] 2> 418962 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 418964 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11c568b main{StandardDirectoryReader(segments_4:6:nrt _1(5.0):C2)}
[junit4] 2> 418965 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
[junit4] 2> 418969 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[3]} 0 1
[junit4] 2> 418972 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[2]} 0 0
[junit4] 2> 418975 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[4]} 0 0
[junit4] 2> 418978 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 418987 T1422 C2829 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_5,generation=5}
[junit4] 2> 418988 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 418990 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@13df958 main
[junit4] 2> 418991 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 418993 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13df958 main{StandardDirectoryReader(segments_5:11:nrt _3(5.0):C4)}
[junit4] 2> 418994 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 16
[junit4] 2> 418995 T1422 oas.SolrTestCaseJ4.tearDown ###Ending testExpungeDeletes
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DirectUpdateHandlerTest -Dtests.method=testExpungeDeletes -Dtests.seed=EB69FED2611887A7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=th_TH -Dtests.timezone=Asia/Aqtau -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.07s J1 | DirectUpdateHandlerTest.testExpungeDeletes <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([EB69FED2611887A7:C710BA5714A14F02]:0)
[junit4] > at org.apache.solr.update.DirectUpdateHandlerTest.testExpungeDeletes(DirectUpdateHandlerTest.java:280)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 419006 T1422 oas.SolrTestCaseJ4.setUp ###Starting testBasics
[junit4] 2> 419009 T1422 C2829 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 419011 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 2
[junit4] 2> 419014 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419015 T1422 C2829 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_6,generation=6}
[junit4] 2> 419015 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 419018 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@12df860 main
[junit4] 2> 419018 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419020 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12df860 main{StandardDirectoryReader(segments_6:12:nrt)}
[junit4] 2> 419020 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 419025 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5]} 0 1
[junit4] 2> 419028 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[6]} 0 0
[junit4] 2> 419029 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:5} hits=0 status=0 QTime=0
[junit4] 2> 419029 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:6} hits=0 status=0 QTime=0
[junit4] 2> 419032 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419037 T1422 C2829 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_7,generation=7}
[junit4] 2> 419037 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 419040 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@1622d04 main
[junit4] 2> 419040 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419042 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1622d04 main{StandardDirectoryReader(segments_7:14:nrt _4(5.0):C2)}
[junit4] 2> 419043 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
[junit4] 2> 419044 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:5} hits=1 status=0 QTime=0
[junit4] 2> 419045 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:6} hits=1 status=0 QTime=0
[junit4] 2> 419048 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {delete=[5]} 0 0
[junit4] 2> 419049 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:5} hits=1 status=0 QTime=0
[junit4] 2> 419052 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419052 T1422 C2829 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 419053 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@189ebf3 main
[junit4] 2> 419054 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419056 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@189ebf3 main{StandardDirectoryReader(segments_7:15:nrt _4(5.0):C2/1)}
[junit4] 2> 419057 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 419057 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:5} hits=0 status=0 QTime=0
[junit4] 2> 419058 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:6} hits=1 status=0 QTime=0
[junit4] 2> 419061 T1422 C2829 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 419062 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 419063 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:6} hits=1 status=0 QTime=0
[junit4] 2> 419066 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419067 T1422 C2829 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_8,generation=8}
[junit4] 2> 419068 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 419069 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@1d7a1bf main
[junit4] 2> 419069 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419072 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d7a1bf main{StandardDirectoryReader(segments_8:16:nrt)}
[junit4] 2> 419073 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 419074 T1422 C2829 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:6} hits=0 status=0 QTime=0
[junit4] 2> 419074 T1422 oas.SolrTestCaseJ4.tearDown ###Ending testBasics
[junit4] 2> 419078 T1422 oas.SolrTestCaseJ4.setUp ###Starting testPrepareCommit
[junit4] 2> 419081 T1422 C2829 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 419082 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 419085 T1422 C2829 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419088 T1422 C2829 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_9,generation=9}
[junit4] 2> 419089 T1422 C2829 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 419089 T1422 C2829 oass.SolrIndexSearcher.<init> Opening Searcher@13117a3 main
[junit4] 2> 419090 T1422 C2829 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419092 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13117a3 main{StandardDirectoryReader(segments_9:17:nrt)}
[junit4] 2> 419093 T1422 C2829 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> ASYNC NEW_CORE C2830 name=collection1 org.apache.solr.core.SolrCore@f104d5
[junit4] 2> 419115 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[999]} 0 0
[junit4] 2> 419119 T1422 C2830 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419123 T1422 C2830 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_a,generation=10}
[junit4] 2> 419123 T1422 C2830 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 419126 T1422 C2830 oass.SolrIndexSearcher.<init> Opening Searcher@1f7fa2d main
[junit4] 2> 419126 T1422 C2830 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419128 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f7fa2d main{StandardDirectoryReader(segments_a:19:nrt _5(5.0):C1)}
[junit4] 2> 419129 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {optimize=} 0 10
[junit4] 2> 419132 T1422 C2830 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419133 T1422 C2830 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 419133 T1422 C2830 oass.SolrIndexSearcher.<init> Opening Searcher@15c727 main
[junit4] 2> 419134 T1422 C2830 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419136 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15c727 main{StandardDirectoryReader(segments_a:19:nrt _5(5.0):C1)}
[junit4] 2> 419137 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 419138 T1422 oasu.DirectUpdateHandlerTest.testPrepareCommit FILES before addDoc=[_5.si, segments_a, _5_Lucene41_0.tim, _5.nvd, _5.fdx, segments.gen, _5_Lucene41_0.doc, _5.nvm, _5.fdt, _5_Lucene41_0.tip, _3_Lucene41_0.tim, _3.nvd, _3.fdt, _3_Lucene41_0.doc, _5.fnm]
[junit4] 2> 419141 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 0
[junit4] 2> 419143 T1422 oasu.DirectUpdateHandlerTest.testPrepareCommit FILES before prepareCommit=[_5.si, segments_a, _5_Lucene41_0.tim, _5.nvd, _6.fdx, _5.fdx, segments.gen, _5_Lucene41_0.doc, _5.nvm, _5.fdt, _5_Lucene41_0.tip, _3_Lucene41_0.tim, _6.fdt, _3.nvd, _3.fdt, _3_Lucene41_0.doc, _5.fnm]
[junit4] 2> 419147 T1422 C2830 oasu.DirectUpdateHandler2.prepareCommit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=true}
[junit4] 2> 419152 T1422 C2830 oasu.DirectUpdateHandler2.prepareCommit end_prepareCommit
[junit4] 2> 419152 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={prepareCommit=true&wt=json&indent=true} {commit=} 0 5
[junit4] 2> 419154 T1422 oasu.DirectUpdateHandlerTest.testPrepareCommit FILES after prepareCommit=[_5.si, segments_a, _5_Lucene41_0.tim, segments_b, _6.fnm, _5.nvd, _6.fdx, _5.fdx, _6.nvd, segments.gen, _6.si, _5_Lucene41_0.doc, _6_Lucene41_0.tim, _5.nvm, _5.fdt, _5_Lucene41_0.tip, _6.nvm, _3_Lucene41_0.tim, _6_Lucene41_0.tip, _6.fdt, _3.nvd, _3.fdt, _3_Lucene41_0.doc, _5.fnm, _6_Lucene41_0.doc]
[junit4] 2> 419155 T1422 C2830 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:1} hits=0 status=0 QTime=0
[junit4] 2> 419163 T1422 C2830 oasu.DirectUpdateHandler2.rollback start rollback{}
[junit4] 2> 419163 T1422 C2830 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 419164 T1422 C2830 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 419164 T1422 C2830 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 419169 T1422 C2830 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_a,generation=10}
[junit4] 2> 419170 T1422 C2830 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 419170 T1422 C2830 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 419171 T1422 C2830 oasu.DirectUpdateHandler2.rollback end_rollback
[junit4] 2> 419171 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={rollback=true&wt=json&indent=true} {rollback=} 0 8
[junit4] 2> 419174 T1422 C2830 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419174 T1422 C2830 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 419176 T1422 C2830 oass.SolrIndexSearcher.<init> Opening Searcher@12ca844 main
[junit4] 2> 419177 T1422 C2830 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419179 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12ca844 main{StandardDirectoryReader(segments_a:19:nrt _5(5.0):C1)}
[junit4] 2> 419179 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 419180 T1422 C2830 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:1} hits=0 status=0 QTime=0
[junit4] 2> 419183 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[1]} 0 0
[junit4] 2> 419188 T1422 C2830 oasu.DirectUpdateHandler2.prepareCommit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=true}
[junit4] 2> 419191 T1422 C2830 oasu.DirectUpdateHandler2.prepareCommit end_prepareCommit
[junit4] 2> 419192 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={prepareCommit=true&wt=json&indent=true} {commit=} 0 4
[junit4] 2> 419192 T1422 C2830 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:1} hits=0 status=0 QTime=0
[junit4] 2> 419195 T1422 C2830 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419196 T1422 C2830 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_b,generation=11}
[junit4] 2> 419197 T1422 C2830 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 419199 T1422 C2830 oass.SolrIndexSearcher.<init> Opening Searcher@5b7241 main
[junit4] 2> 419199 T1422 C2830 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419202 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5b7241 main{StandardDirectoryReader(segments_b:21:nrt _5(5.0):C1 _6(5.0):C1)}
[junit4] 2> 419203 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> 419203 T1422 C2830 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:1} hits=1 status=0 QTime=0
[junit4] 2> 419205 T1422 oas.SolrTestCaseJ4.tearDown ###Ending testPrepareCommit
[junit4] 2> 419209 T1422 oas.SolrTestCaseJ4.setUp ###Starting testDeleteRollback
[junit4] 2> 419212 T1422 C2830 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 419212 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 419215 T1422 C2830 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419217 T1422 C2830 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b3313)),segFN=segments_c,generation=12}
[junit4] 2> 419217 T1422 C2830 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 419219 T1422 C2830 oass.SolrIndexSearcher.<init> Opening Searcher@5ee63e main
[junit4] 2> 419220 T1422 C2830 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 419222 T1424 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5ee63e main{StandardDirectoryReader(segments_c:22:nrt)}
[junit4] 2> 419224 T1422 C2830 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 9
[junit4] 2> 419224 T1422 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 419225 T1422 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=21898349
[junit4] 2> 419225 T1422 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f104d5
[junit4] 2> 419243 T1422 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=13,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=1,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=10,cumulative_deletesById=1,cumulative_deletesByQuery=6,cumulative_errors=0}
[junit4] 2> 419244 T1422 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 419244 T1422 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 419244 T1422 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 419245 T1422 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 419246 T1422 oasc.CachingDirectoryFactory.close Closing MockFSDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 431366 T1422 oasc.CachingDirectoryFactory.close ERROR Timeout waiting for all directory ref counts to be released - gave up waiting on CachedDir<<refCount=1;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index;done=false>>
[junit4] 2> 431368 T1422 oasc.SolrException.log ERROR Error closing directory:org.apache.solr.common.SolrException: Timeout waiting for all directory ref counts to be released - gave up waiting on CachedDir<<refCount=1;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761/index;done=false>>
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:185)
[junit4] 2> at org.apache.solr.core.SolrCore.close(SolrCore.java:1041)
[junit4] 2> at org.apache.solr.core.SolrCores.close(SolrCores.java:120)
[junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:367)
[junit4] 2> at org.apache.solr.util.TestHarness.close(TestHarness.java:338)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:535)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandlerTest.testDeleteRollback(DirectUpdateHandlerTest.java:194)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:491)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[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:70)
[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:358)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[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 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:70)
[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:358)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 431369 T1422 oasc.SolrException.log ERROR Error closing directory:java.lang.AssertionError: 1
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.close(CachingDirectoryFactory.java:199)
[junit4] 2> at org.apache.solr.core.SolrCore.close(SolrCore.java:1041)
[junit4] 2> at org.apache.solr.core.SolrCores.close(SolrCores.java:120)
[junit4] 2> at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:367)
[junit4] 2> at org.apache.solr.util.TestHarness.close(TestHarness.java:338)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteCore(SolrTestCaseJ4.java:535)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandlerTest.testDeleteRollback(DirectUpdateHandlerTest.java:194)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:491)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[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:70)
[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:358)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[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 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:70)
[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:358)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 431370 T1422 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761;done=false>>]
[junit4] 2> 431370 T1422 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756689761
[junit4] 2> 431374 T1422 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-DirectUpdateHandlerTest-1375756702836
[junit4] 2> 431375 T1422 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 431375 T1422 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 431376 T1422 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 431408 T1422 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 431432 T1422 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 431433 T1422 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 431439 T1422 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 431567 T1422 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 431569 T1422 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 431570 T1422 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 431575 T1422 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 431577 T1422 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 431578 T1422 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 431579 T1422 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 431579 T1422 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 431589 T1422 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 431590 T1422 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 431590 T1422 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 431608 T1422 oasc.CoreContainer.<init> New CoreContainer 720804
[junit4] 2> 431609 T1422 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 431609 T1422 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 431610 T1422 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 431610 T1422 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 431610 T1422 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 431611 T1422 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 431611 T1422 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 431611 T1422 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 431612 T1422 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 431612 T1422 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 431612 T1422 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 431615 T1422 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 431618 T1426 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 431619 T1426 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 431619 T1426 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 431619 T1426 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 431652 T1426 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 431676 T1426 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 431677 T1426 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 431683 T1426 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 431817 T1426 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 431818 T1426 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 431819 T1426 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 431824 T1426 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 431826 T1426 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 431827 T1426 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockFSDirectoryFactory
[junit4] 2> 431828 T1426 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-DirectUpdateHandlerTest-1375756702836/
[junit4] 2> 431828 T1426 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d0a4c
[junit4] 2> 431829 T1426 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756702836
[junit4] 2> 431829 T1426 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-DirectUpdateHandlerTest-1375756702836/index/
[junit4] 2> 431829 T1426 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-DirectUpdateHandlerTest-1375756702836/index' doesn't exist. Creating new index...
[junit4] 2> 431830 T1426 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756702836/index
[junit4] 2> 431831 T1426 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756702836/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@91e81d)),segFN=segments_1,generation=1}
[junit4] 2> 431832 T1426 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 431832 T1426 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 431835 T1426 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 431835 T1426 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 431835 T1426 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 431836 T1426 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 431836 T1426 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 431837 T1426 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 431837 T1426 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 431838 T1426 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 431838 T1426 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 431839 T1426 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 431839 T1426 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 431840 T1426 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 431840 T1426 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 431840 T1426 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 431841 T1426 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 431841 T1426 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 431842 T1426 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 431842 T1426 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 431843 T1426 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 431843 T1426 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 431844 T1426 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 431844 T1426 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 431844 T1426 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 431845 T1426 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 431845 T1426 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 431846 T1426 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 431848 T1426 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 431849 T1426 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 431850 T1426 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, SOLRCONFIG-HIGHLIGHT.XML, SCHEMA-REQUIRED-FIELDS.XML, SCHEMA-REPLICATION2.XML, TRIVIAL.UPDATEPROCESSOR1.JS, SCHEMA-MINIMAL.XML, BAD-SCHEMA-DOCVALUES-NOT-REQUIRED-NO-DEFAULT.XML, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, SOLRCONFIG-CACHING.XML, BAD-SOLRCONFIG-UNEXPECTED-SCHEMA-ATTRIBUTE.XML, SCHEMA-POSTINGSHIGHLIGHT.XML, SOLRCONFIG-REPEATER.XML, REGEX-BOOST-PROCESSOR-TEST.TXT, SOLRCONFIG-MERGEPOLICY.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, BAD-SCHEMA-CURRENCY-FT-BOGUS-CODE-IN-XML.XML, CURRENCY.XML, SOLRCONFIG-TLOG.XML, SOLRCONFIG-MASTER.XML, SOLRCONFIG-COMPONENTS-NAME.XML, SOLRCONFIG-MERGEPOLICY-LEGACY.XML, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, SCHEMA11.XML, SOLRCONFIG-BASIC.XML, DA_COMPOUNDDICTIONARY.TXT, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SCHEMA-COPYFIELD-TEST.XML, SOLRCONFIG-SLAVE.XML, ELEVATE.XML, TRIVIAL.UPDATEPROCESSOR0.JS, SCHEMA-SWEETSPOT.XML, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML, SCHEMA-IB.XML, SCHEMA-CHARFILTERS.XML, BAD-SCHEMA-MISPLACED-ASTERISK-COPYFIELD-SOURCE-SHOULD-FAIL-TEST.XML, SOLRCONFIG-QUERYSENDER.XML, SCHEMA-REPLICATION1.XML, SOLRCONFIG-SNIPPET-PROCESSOR.XML, DA_UTF8.XML, BAD-SOLRCONFIG-MULTIPLE-INDEXCONFIGS.XML, BAD-SCHEMA-NON-GLOB-COPYFIELD-SOURCE-MATCHING-NOTHING-SHOULD-FAIL-TEST.XML, SCHEMA-TINY.XML, CONDITIONAL.UPDATEPROCESSOR.JS, SOLRCONFIG-DEFAULTS.XML, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, SOLRCONFIG-ENABLEPLUGIN.XML, HYPHENATION.DTD, BAD-SOLRCONFIG-SCHEMA-MUTABLE-BUT-NOT-MANAGED.XML, STEMDICT.TXT, SCHEMA-PHRASESUGGEST.XML, SCHEMA-ID-AND-VERSION-FIELDS-ONLY.XML, HUNSPELL-TEST.AFF, SCHEMA-ONE-FIELD-NO-DYNAMIC-FIELD-UNIQUE-KEY.XML, BAD-SCHEMA-CURRENCY-FT-OER-NORATES.XML, SCHEMA-SNIPPET-TYPE.XML, STOPTYPES-1.TXT, STOPWORDSWRONGENCODING.TXT, SCHEMA-NUMERIC.XML, SOLRCONFIG-TRANSFORMERS.XML, BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, SOLRCONFIG-SIMPLELOCK.XML, SCHEMA-DOCVALUES.XML, SOLRCONFIG-TLOG-MUTABLE-MANAGED-SCHEMA.XML, SOLRCONFIG-INFOSTREAM-LOGGING.XML, WDFTYPES.TXT, SCHEMA-REVERSED.XML, STOPTYPES-2.TXT, BAD-SCHEMA-CURRENCY-FT-MULTIVALUED.XML, BAD-SCHEMA-BOGUS-ANALYSIS-PARAMETERS.XML, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, BAD-SCHEMA-CURRENCY-MULTIVALUED.XML, SOLRCONFIG-PHRASESUGGEST.XML, SCHEMA-DFR.XML, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, KEEP-1.TXT, SCHEMA-SPATIAL.XML, OPEN-EXCHANGE-RATES.JSON, STOPWITHBOM.TXT, SCHEMA-BINARYFIELD.XML, SOLRCONFIG-SPELLCHECKER.XML, SOLRCONFIG-POSTINGSHIGHLIGHT.XML, SOLRCONFIG-WARMER.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, BAD-SCHEMA-DUP-FIELDTYPE.XML, SCHEMA-XINCLUDE.XML, SOLRCONFIG-MASTER1.XML, BAD-SCHEMA-MULTIPLE-ASTERISK-COPYFIELD-SOURCE-SHOULD-FAIL-TEST.XML, SCHEMA-BEHAVIOR.XML, SYNONYMS.TXT, SOLRCONFIG-MINIMAL.XML, SCHEMA-DOCVALUESMULTI.XML, BAD-SCHEMA-CURRENCY-DYNAMIC-MULTIVALUED.XML, BAD-SCHEMA-UNSUPPORTED-DOCVALUES.XML, SCHEMA_CODEC.XML, SCHEMA-SNIPPET-TYPES.INCL, BAD-SCHEMA-SWEETSPOT-PARTIAL-HYPERBOLIC.XML, SOLRCONFIG-SOLR-749.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, SCHEMA-EFF.XML, SOLRCONFIG-FUNCTIONQUERY.XML, STOP-2.TXT, SCHEMA-LMDIRICHLET.XML, SOLRCONFIG-TERMINDEX.XML, SCHEMA-ONE-FIELD-NO-DYNAMIC-FIELD.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-NORMS.XML, SOLRCONFIG-ELEVATE.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-BASELINE.XML, STOPWORDS.TXT, SCHEMA-FOLDING.XML, SCHEMA-STOP-KEEP.XML, BAD-SCHEMA-BOGUS-FIELD-PARAMETERS.XML, FUZZYSUGGEST.TXT, BAD-SOLRCONFIG-MANAGED-SCHEMA-NAMED-SCHEMA.XML.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, SCHEMA-REST.XML, SOLRCONFIG-TLOG-MANAGED-SCHEMA.XML, SOLRCONFIG-PARSING-UPDATE-PROCESSOR-CHAINS.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, STOP-1.TXT, SOLRCONFIG-MANAGED-SCHEMA.XML, SOLRCONFIG-MASTER2.XML, SOLRCONFIG-MERGEPOLICY-DEFAULTS.XML, SCHEMA-SPELLCHECKER.XML, SOLRCONFIG-RESPONSE-LOG-COMPONENT.XML, BAD-SCHEMA-MISPLACED-ASTERISK-COPYFIELD-DEST-SHOULD-FAIL-TEST.XML, SOLRCONFIG-LAZYWRITER.XML, SCHEMA-LUCENEMATCHVERSION.XML, SCHEMA-CLASS-NAME-SHORTENING-ON-SERIALIZATION.XML, FRENCHARTICLES.TXT, BAD-MP-SOLRCONFIG.XML, SCHEMA15.XML, SOLRCONFIG-REQHANDLER.INCL, SCHEMASURROUND.XML, SOLRCONFIG-MASTER3.XML, HUNSPELL-TEST.DIC, BAD-SCHEMA-MULTIPLE-ASTERISK-COPYFIELD-DEST-SHOULD-FAIL-TEST.XML, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-XINCLUDE.XML, SOLRCONFIG-SLAVE1.XML, SOLRCONFIG-DELPOLICY1.XML, SCHEMA-SIM.XML, SCHEMA-SNIPPET-FIELD.XML, SCHEMA-COLLATE.XML, STOP-SNOWBALL.TXT, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, PROTWORDS.TXT, SOLRCONFIG-ADD-SCHEMA-FIELDS-UPDATE-PROCESSOR-CHAINS.XML, SCHEMA-TRIE.XML, SOLRCONFIG_CODEC.XML, SCHEMA-SYNONYM-TOKENIZER.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, JASUGGEST.TXT, SCHEMA-REST-LUCENE-MATCH-VERSION.XML, BAD-SOLRCONFIG-MULTIPLE-CFS.XML, SCHEMA-DOCVALUESFACETING.XML, SCHEMA-TFIDF.XML, SCHEMA-ADD-SCHEMA-FIELDS-UPDATE-PROCESSOR.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, SCHEMA-LMJELINEKMERCER.XML, PHRASESUGGEST.TXT, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, OLD_SYNONYMS.TXT, SOLRCONFIG-DELPOLICY2.XML, XSLT, SOLRCONFIG-NATIVELOCK.XML, BAD-SCHEMA-DUP-FIELD.XML, SOLRCONFIG-MUTABLE-MANAGED-SCHEMA.XML, SOLRCONFIG-NOCACHE.XML, SCHEMA-BM25.XML, BAD-SCHEMA-SWEETSPOT-BOTH-TF.XML, ADDFIELDS.UPDATEPROCESSOR.JS, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, SOLRCONFIG-ALTDIRECTORY.XML, COMPOUNDDICTIONARY.TXT, SOLRCONFIG_PERF.XML, BAD-SCHEMA-CURRENCY-FT-BOGUS-DEFAULT-CODE.XML, BAD-CURRENCY.XML, SOLRCONFIG-INDEXCONFIG.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, KEEP-2.TXT, SCHEMA12.XML, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, MAPPING-ISOLATIN1ACCENT.TXT, BAD_SOLRCONFIG.XML, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML]
[junit4] 2> 431852 T1426 oass.SolrIndexSearcher.<init> Opening Searcher@a0d6b6 main
[junit4] 2> 431853 T1426 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 431853 T1426 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 431853 T1426 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 432318 T1426 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 432337 T1427 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a0d6b6 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 432342 T1426 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 432346 T1422 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C2831 name=collection1 org.apache.solr.core.SolrCore@537191
[junit4] 2> 432352 T1422 C2831 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756702836/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@91e81d)),segFN=segments_1,generation=1}
[junit4] 2> 432353 T1422 C2831 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 432354 T1422 C2831 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[A]} 0 4
[junit4] 2> 432359 T1422 C2831 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[B]} 0 1
[junit4] 2> 432359 T1422 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 432363 T1422 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756702836/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@91e81d)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756702836/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@91e81d
[...truncated too long message...]
7 T1422 oasu.DirectUpdateHandler2.rollback start rollback{}
[junit4] 2> 433108 T1422 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 433108 T1422 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 433108 T1422 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 433112 T1422 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13a3536)),segFN=segments_2,generation=2}
[junit4] 2> 433112 T1422 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 433113 T1422 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 433113 T1422 oasu.DirectUpdateHandler2.rollback end_rollback
[junit4] 2> 433113 T1422 C2833 oasc.SolrCore.execute [collection1] webapp=null path=null params={indent=true&q=id:A+OR+id:B} hits=1 status=0 QTime=0
[junit4] 2> 433118 T1422 C2833 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[ZZZ]} 0 1
[junit4] 2> 433121 T1422 C2833 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 433127 T1422 C2833 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13a3536)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13a3536)),segFN=segments_3,generation=3}
[junit4] 2> 433128 T1422 C2833 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 433132 T1422 C2833 oass.SolrIndexSearcher.<init> Opening Searcher@927c7b main
[junit4] 2> 433132 T1422 C2833 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 433135 T1430 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@927c7b main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C1 _1(5.0):C1)}
[junit4] 2> 433135 T1422 C2833 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
[junit4] 2> 433136 T1422 C2833 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=id:ZZZ} hits=1 status=0 QTime=0
[junit4] 2> 433137 T1422 oas.SolrTestCaseJ4.tearDown ###Ending testAddRollback
[junit4] 2> 433139 T1422 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 433139 T1422 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=27728984
[junit4] 2> 433139 T1422 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ca7e40
[junit4] 2> 433158 T1422 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=1,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 433158 T1422 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 433158 T1422 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 433159 T1422 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 433159 T1422 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 433160 T1422 oasc.CachingDirectoryFactory.close Closing MockFSDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 433160 T1422 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941;done=false>>]
[junit4] 2> 433160 T1422 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941
[junit4] 2> 433161 T1422 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941/index;done=false>>]
[junit4] 2> 433161 T1422 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solrtest-DirectUpdateHandlerTest-1375756703941/index
[junit4] 2> 554186 T1422 oas.SolrTestCaseJ4.endTrackingSearchers ERROR ERROR: SolrIndexSearcher opens=22 closes=21
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=227), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=227)), sim=DefaultSimilarity, locale=th_TH, timezone=Asia/Aqtau
[junit4] 2> NOTE: Linux 3.2.0-51-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=69413688,total=289406976
[junit4] 2> NOTE: All tests run in this JVM: [TestArbitraryIndexDir, TestIndexSearcher, SampleTest, TestRecoveryHdfs, OpenExchangeRatesOrgProviderTest, TestReloadAndDeleteDocs, OutputWriterTest, NotRequiredUniqueKeyTest, CoreAdminCreateDiscoverTest, TestFoldingMultitermQuery, TestQuerySenderNoQuery, TestSchemaSimilarityResource, PingRequestHandlerTest, SimplePostToolTest, BadIndexSchemaTest, UpdateParamsTest, TestZkChroot, SpellCheckCollatorTest, TestBinaryField, RequestHandlersTest, TestHashPartitioner, TestDefaultSimilarityFactory, IndexBasedSpellCheckerTest, DebugComponentTest, CopyFieldTest, TestManagedSchemaFieldResource, HdfsCollectionsAPIDistributedZkTest, TestRandomMergePolicy, HdfsBasicDistributedZk2Test, HighlighterTest, TestWordDelimiterFilterFactory, HighlighterConfigTest, DocValuesMultiTest, PathHierarchyTokenizerFactoryTest, FullSolrCloudDistribCmdsTest, BlockCacheTest, TestRecovery, OpenCloseCoreStressTest, SolrCoreCheckLockOnStartupTest, FileUtilsTest, StandardRequestHandlerTest, TestOmitPositions, MultiTermTest, ZkNodePropsTest, TestCloudManagedSchema, TestNumberUtils, ShardSplitTest, TestUtils, BadCopyFieldTest, HdfsLockFactoryTest, FieldAnalysisRequestHandlerTest, TestAtomicUpdateErrorCases, TestJmxIntegration, TestAnalyzedSuggestions, ConvertedLegacyTest, SuggesterWFSTTest, CurrencyFieldXmlFileTest, SignatureUpdateProcessorFactoryTest, TermVectorComponentDistributedTest, HdfsSyncSliceTest, NumericFieldsTest, SortByFunctionTest, TestUpdate, TestLMJelinekMercerSimilarityFactory, TestStressReorder, TimeZoneUtilsTest, TestLuceneMatchVersion, TestIndexingPerformance, HdfsUnloadDistributedZkTest, StatsComponentTest, DirectUpdateHandlerTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DirectUpdateHandlerTest -Dtests.seed=EB69FED2611887A7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=th_TH -Dtests.timezone=Asia/Aqtau -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | DirectUpdateHandlerTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=22 closes=21
[junit4] > at __randomizedtesting.SeedInfo.seed([EB69FED2611887A7]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:326)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:132)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] Completed on J1 in 135.92s, 6 tests, 2 failures <<< FAILURES!
[...truncated 516 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:389: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:369: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1230: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:873: There were test failures: 318 suites, 1342 tests, 1 suite-level error, 1 failure, 36 ignored (6 assumptions)
Total time: 39 minutes 37 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b99 -client -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure