You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/01/01 19:22:18 UTC

[JENKINS] Lucene-Solr-Tests-master - Build # 1580 - Unstable

Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1580/

1 tests failed.
FAILED:  org.apache.solr.update.HardAutoCommitTest.testCommitWithin

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([88753DBA4F6412FA:32A752C2CC4AFCEF]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:818)
	at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
	xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
</response>

	request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:811)
	... 40 more




Build Log:
[...truncated 11767 lines...]
   [junit4] Suite: org.apache.solr.update.HardAutoCommitTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.HardAutoCommitTest_88753DBA4F6412FA-001/init-core-data-001
   [junit4]   2> 973364 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 973364 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 973365 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 973421 WARN  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 973424 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 973480 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 973738 WARN  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 973751 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 974225 WARN  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 974227 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d35c52
   [junit4]   2> 974227 WARN  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 974230 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d35c52
   [junit4]   2> 974230 WARN  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 974230 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d35c52
   [junit4]   2> 974230 WARN  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 974231 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d35c52
   [junit4]   2> 974231 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 974258 WARN  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 974260 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 974288 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 974422 WARN  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 974424 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 974430 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 974431 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.HardAutoCommitTest_88753DBA4F6412FA-001/init-core-data-001/]
   [junit4]   2> 974431 WARN  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 974431 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d35c52
   [junit4]   2> 974432 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d35c52
   [junit4]   2> 974432 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=81.025390625, floorSegmentMB=1.1806640625, forceMergeDeletesPctAllowed=19.552353779624404, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 974450 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 974450 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=18.2236328125, floorSegmentMB=2.12109375, forceMergeDeletesPctAllowed=17.687522691203917, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6742574098437382
   [junit4]   2> 974451 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ffe207[collection1] main]
   [junit4]   2> 974452 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 974458 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 974475 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 974476 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 974476 INFO  (coreLoadExecutor-3942-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1555349122418999296
   [junit4]   2> 974478 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 974478 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 974478 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 974482 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 974483 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 974483 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 974487 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 974489 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 974489 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 974490 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
   [junit4]   2> 974492 INFO  (searcherExecutor-3943-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ffe207[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 974492 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:* (-1555349122434727936)} 0 1
   [junit4]   2> 974494 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 974579 WARN  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 974584 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 974606 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 974702 WARN  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 974704 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 974708 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 974727 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.HardAutoCommitTest_88753DBA4F6412FA-001/init-core-data-001/]
   [junit4]   2> 974727 WARN  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 974751 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d35c52
   [junit4]   2> 974752 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.m.SolrMetricManager Replacing existing reporter 'default' in registry 'solr.core.collection1': [org.apache.solr.metrics.reporters.SolrJmxReporter@6fedcf21: domain = solr.core.collection1, service url = null, agent id = null]
   [junit4]   2> 974754 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@15d35c52
   [junit4]   2> 975009 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 975009 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 975009 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6947fa64[collection1] main]
   [junit4]   2> 975009 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 975010 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 975011 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 975014 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=37, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 975021 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 975021 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 975021 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 975021 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 975021 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 975021 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 975021 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 975022 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 975022 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 975022 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 975022 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6947fa64[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975023 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e1f80b8[collection1] main]
   [junit4]   2> 975023 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@c545017
   [junit4]   2> 975027 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 975032 INFO  (searcherExecutor-3948-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e1f80b8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 975033 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1555349123002007552)]} 0 3
   [junit4]   2> 975033 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 975034 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1555349123004104704)]} 0 4
   [junit4]   2> 975034 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 975035 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1555349123005153280)]} 0 5
   [junit4]   2> 975035 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 975035 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1]
   [junit4]   2> 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2> 	request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 975035 ERROR (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=id:529&qt=standard&start=0&rows=20&version=2.2:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
   [junit4]   2> 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2> 	request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:811)
   [junit4]   2> 	at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:498)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 975036 INFO  (TEST-HardAutoCommitTest.testCommitWithin-seed#[88753DBA4F6412FA]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=HardAutoCommitTest -Dtests.method=testCommitWithin -Dtests.seed=88753DBA4F6412FA -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-CO -Dtests.timezone=Asia/Qatar -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.55s J0 | HardAutoCommitTest.testCommitWithin <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([88753DBA4F6412FA:32A752C2CC4AFCEF]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:818)
   [junit4]    > 	at org.apache.solr.update.HardAutoCommitTest.testCommitWithin(HardAutoCommitTest.java:100)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
   [junit4]    > 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]    > <response>
   [junit4]    > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
   [junit4]    > </response>
   [junit4]    > 	request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:811)
   [junit4]    > 	... 40 more
   [junit4]   2> 975039 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 975040 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=156754795
   [junit4]   2> 975040 INFO  (coreCloseExecutor-3952-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@674f508c
   [junit4]   2> 975046 INFO  (coreCloseExecutor-3952-thread-1-processing-x:collection1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 975046 INFO  (SUITE-HardAutoCommitTest-seed#[88753DBA4F6412FA]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.HardAutoCommitTest_88753DBA4F6412FA-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=19, maxMBSortInHeap=5.178784253386152, sim=RandomSimilarity(queryNorm=true): {}, locale=es-CO, timezone=Asia/Qatar
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=4,threads=1,free=169300536,total=530579456
   [junit4]   2> NOTE: All tests run in this JVM: [TestRangeQuery, TestReplicationHandler, DistributedFacetPivotSmallTest, TestRandomFlRTGCloud, TestRemoteStreaming, TestRTGBase, TestSolrCloudWithHadoopAuthPlugin, HdfsThreadLeakTest, CurrencyFieldOpenExchangeTest, SpellPossibilityIteratorTest, TestQuerySenderListener, BlockCacheTest, TestFieldCollectionResource, DateMathParserTest, TestNRTOpen, TestFieldTypeResource, TestSolrQueryParserDefaultOperatorResource, RegexBoostProcessorTest, TestSolrConfigHandlerConcurrent, XsltUpdateRequestHandlerTest, TestUseDocValuesAsStored2, DistributedQueryElevationComponentTest, TestHdfsUpdateLog, TestSha256AuthenticationProvider, TemplateUpdateProcessorTest, TestExpandComponent, QueryEqualityTest, TestBulkSchemaAPI, NotRequiredUniqueKeyTest, TestFieldCache, DistributedQueryComponentOptimizationTest, TestManagedSchemaThreadSafety, TestRealTimeGet, UUIDFieldTest, TestLazyCores, TestCSVLoader, TestExactSharedStatsCache, ZkStateReaderTest, DocValuesMissingTest, CopyFieldTest, SolrCoreTest, AtomicUpdatesTest, SecurityConfHandlerTest, PingRequestHandlerTest, DistribDocExpirationUpdateProcessorTest, TestEmbeddedSolrServerConstructors, TestLMJelinekMercerSimilarityFactory, TestSortByMinMaxFunction, DefaultValueUpdateProcessorTest, PathHierarchyTokenizerFactoryTest, LeaderElectionIntegrationTest, HLLSerializationTest, BitVectorTest, CacheHeaderTest, RequiredFieldsTest, TestReloadDeadlock, TestSolrDeletionPolicy2, TestRandomDVFaceting, TestSmileRequest, FastVectorHighlighterTest, DistributedDebugComponentTest, TestScoreJoinQPNoScore, JavabinLoaderTest, DirectoryFactoryTest, MetricUtilsTest, ActionThrottleTest, UUIDUpdateProcessorFallbackTest, CreateCollectionCleanupTest, ZkNodePropsTest, SignatureUpdateProcessorFactoryTest, HdfsBasicDistributedZkTest, TestDFRSimilarityFactory, TestDFISimilarityFactory, TestFileDictionaryLookup, BasicZkTest, TestSizeLimitedDistributedMap, TestRandomFaceting, RuleEngineTest, TestConfigSetImmutable, LeaderFailureAfterFreshStartTest, TestFaceting, TestJsonFacets, TestAnalyzedSuggestions, PolyFieldTest, TestCloudRecovery, SmileWriterTest, MultiTermTest, TestHighlightDedupGrouping, TestPseudoReturnFields, TestOmitPositions, OverriddenZkACLAndCredentialsProvidersTest, AsyncCallRequestStatusResponseTest, BooleanFieldTest, TestLeaderElectionWithEmptyReplica, TestCloudInspectUtil, TestSolr4Spatial, PeerSyncTest, SearchHandlerTest, TestReplicationHandlerBackup, HdfsRecoveryZkTest, FileBasedSpellCheckerTest, TestCryptoKeys, CoreMergeIndexesAdminHandlerTest, CloudExitableDirectoryReaderTest, TestJavabinTupleStreamParser, TestJoin, QueryResultKeyTest, DistributedFacetPivotLargeTest, FullSolrCloudDistribCmdsTest, TestDistributedGrouping, TermVectorComponentDistributedTest, HardAutoCommitTest]
   [junit4] Completed [355/673 (1!)] on J0 in 1.78s, 1 test, 1 error <<< FAILURES!

[...truncated 63442 lines...]