lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-master - Build # 1822 - Still Unstable
Date Tue, 09 May 2017 02:43:27 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1822/

1 tests failed.
FAILED:  org.apache.solr.update.AutoCommitTest.testMaxDocs

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([814E8478C4B1CDE6:38CF52A7E85BC96C]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:896)
	at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:225)
	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:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	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:368)
	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:14&qt=standard&start=0&rows=20&version=2.2
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:889)
	... 40 more




Build Log:
[...truncated 12170 lines...]
   [junit4] Suite: org.apache.solr.update.AutoCommitTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.update.AutoCommitTest_814E8478C4B1CDE6-001/init-core-data-001
   [junit4]   2> 1141495 WARN  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1141495 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1141496 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1141516 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1141516 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-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> 1141567 WARN  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1141569 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1141607 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1141908 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1141989 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f2df7ed
   [junit4]   2> 1141998 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f2df7ed
   [junit4]   2> 1141998 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f2df7ed
   [junit4]   2> 1142001 INFO  (coreLoadExecutor-5261-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> 1142117 WARN  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1142121 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1142162 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1142407 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1142415 INFO  (coreLoadExecutor-5261-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, trusted=true
   [junit4]   2> 1142416 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f2df7ed
   [junit4]   2> 1142416 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1142416 INFO  (coreLoadExecutor-5261-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.AutoCommitTest_814E8478C4B1CDE6-001/init-core-data-001/]
   [junit4]   2> 1142418 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5463091155843297]
   [junit4]   2> 1142550 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1142550 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1142551 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1142551 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1142552 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=64.74609375, floorSegmentMB=1.0087890625, forceMergeDeletesPctAllowed=27.204963010696147, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.765001125224141
   [junit4]   2> 1142552 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1490133[collection1] main]
   [junit4]   2> 1142553 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1142584 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1142657 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1142657 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1142657 INFO  (coreLoadExecutor-5261-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1566882708064305152
   [junit4]   2> 1142658 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1142658 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1142658 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1142658 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1142658 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1142658 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1142659 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1142659 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1142660 INFO  (searcherExecutor-5262-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1490133[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1142664 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1142667 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
   [junit4]   2> 1142667 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1142667 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3eb7539e[collection1] realtime]
   [junit4]   2> 1142668 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 1
   [junit4]   2> 1142668 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties
   [junit4]   2> 1142670 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] 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> 1142714 WARN  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1142716 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1142816 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1143033 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1143051 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    ] 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> 1144356 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f2df7ed
   [junit4]   2> 1144356 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    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.AutoCommitTest_814E8478C4B1CDE6-001/init-core-data-001/]
   [junit4]   2> 1144674 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1144674 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1144676 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@482a826e[collection1] main]
   [junit4]   2> 1144677 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1144686 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1144689 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1144689 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1144690 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1144690 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1144690 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1144690 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1144690 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1144691 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1144691 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1144691 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1144694 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@482a826e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1144694 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1566882710200254464
   [junit4]   2> 1144697 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=26.0419921875, floorSegmentMB=1.0458984375, forceMergeDeletesPctAllowed=3.327234566324331, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1144698 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1144698 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@421e3dc0[collection1] main]
   [junit4]   2> 1144701 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@421e3dc0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1144701 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5ece9d54
   [junit4]   2> 1144703 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1590598996
   [junit4]   2> 1144762 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1566882710238003200)]} 0 32
   [junit4]   2> 1144762 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    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> 1146234 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1146240 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4d13e6f7[collection1] main]
   [junit4]   2> 1146240 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1146243 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4d13e6f7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 1146307 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1566882711890558976)]} 0 1577
   [junit4]   2> 1146307 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    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=1 status=0 QTime=0
   [junit4]   2> 1146308 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    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> 1146308 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{delete=[529 (-1566882711892656128)]} 0 0
   [junit4]   2> 1146309 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    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=1 status=0 QTime=0
   [junit4]   2> 1147807 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1147810 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13bdc19f[collection1] main]
   [junit4]   2> 1147810 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1147812 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13bdc19f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C1)))}
   [junit4]   2> 1148061 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[550 (1566882713729761280)]} 0 3331
   [junit4]   2> 1148061 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    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> 1148062 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713731858432)]} 0 3332
   [junit4]   2> 1148062 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713731858433)]} 0 3333
   [junit4]   2> 1148062 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713731858434)]} 0 3333
   [junit4]   2> 1148063 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713732907008)]} 0 3333
   [junit4]   2> 1148063 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713732907009)]} 0 3334
   [junit4]   2> 1148063 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713732907010)]} 0 3334
   [junit4]   2> 1148064 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713733955584)]} 0 3334
   [junit4]   2> 1148064 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713733955585)]} 0 3334
   [junit4]   2> 1148064 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713733955586)]} 0 3334
   [junit4]   2> 1148064 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882713733955587)]} 0 3335
   [junit4]   2> 1148064 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1149561 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1149566 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d1eae10[collection1] main]
   [junit4]   2> 1149566 INFO  (commitScheduler-5270-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1149568 INFO  (searcherExecutor-5267-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d1eae10[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C11/9:delGen=1)))}
   [junit4]   2> 1149816 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[531 (1566882715570012160)]} 0 5086
   [junit4]   2> 1149816 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 1149817 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
   [junit4]   2> 1149820 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
   [junit4]   2> 1149820 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1149821 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@31ee4cad[collection1] realtime]
   [junit4]   2> 1149821 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 0
   [junit4]   2> 1149821 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties
   [junit4]   2> 1149822 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    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> 1149867 WARN  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1149869 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1149897 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1150084 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1150095 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] 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> 1150121 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f2df7ed
   [junit4]   2> 1150121 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    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.AutoCommitTest_814E8478C4B1CDE6-001/init-core-data-001/]
   [junit4]   2> 1150230 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1150230 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1150232 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@36a0f40a[collection1] main]
   [junit4]   2> 1150233 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1150234 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1150242 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1150246 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8241472598432611]
   [junit4]   2> 1150247 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1150248 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1150248 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1150248 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1150248 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1150249 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1150249 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1150249 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1150250 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1150250 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1150254 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@36a0f40a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1150255 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@47563ff[collection1] main]
   [junit4]   2> 1150255 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6ad6607
   [junit4]   2> 1150256 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=112027143
   [junit4]   2> 1150262 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1566882716036628480)]} 0 2
   [junit4]   2> 1150262 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@47563ff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1150262 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1566882716038725632)]} 0 2
   [junit4]   2> 1150263 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1566882716038725633)]} 0 2
   [junit4]   2> 1150263 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1566882716039774208)]} 0 2
   [junit4]   2> 1150263 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1566882716039774209)]} 0 3
   [junit4]   2> 1150264 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1566882716039774210)]} 0 3
   [junit4]   2> 1150264 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1566882716040822784)]} 0 3
   [junit4]   2> 1150264 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1566882716040822785)]} 0 4
   [junit4]   2> 1150264 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1566882716040822786)]} 0 4
   [junit4]   2> 1150264 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1566882716040822787)]} 0 4
   [junit4]   2> 1150265 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1566882716040822788)]} 0 4
   [junit4]   2> 1150265 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1566882716041871360)]} 0 5
   [junit4]   2> 1150265 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1566882716041871361)]} 0 5
   [junit4]   2> 1150266 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1566882716042919936)]} 0 5
   [junit4]   2> 1150266 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1150268 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1566882716042919937)]} 0 8
   [junit4]   2> 1150268 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1566882716045017088)]} 0 8
   [junit4]   2> 1150268 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1150269 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    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:14&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 1150269 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=id:14&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:14&qt=standard&start=0&rows=20&version=2.2
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:889)
   [junit4]   2> 	at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:225)
   [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:368)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
   [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:368)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1150270 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
   [junit4]   2> 1150271 INFO  (commitScheduler-5274-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoCommitTest -Dtests.method=testMaxDocs -Dtests.seed=814E8478C4B1CDE6 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=fr -Dtests.timezone=Europe/Zagreb -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.46s J0 | AutoCommitTest.testMaxDocs <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([814E8478C4B1CDE6:38CF52A7E85BC96C]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:896)
   [junit4]    > 	at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:225)
   [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:14&qt=standard&start=0&rows=20&version=2.2
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:889)
   [junit4]    > 	... 40 more
   [junit4]   2> 1150279 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
   [junit4]   2> 1150286 INFO  (commitScheduler-5274-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@24a59260[collection1] main]
   [junit4]   2> 1150286 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1150287 INFO  (commitScheduler-5274-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1150288 INFO  (searcherExecutor-5271-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@24a59260[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C16)))}
   [junit4]   2> 1150288 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@314f170b[collection1] realtime]
   [junit4]   2> 1150288 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 9
   [junit4]   2> 1150289 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties
   [junit4]   2> 1150289 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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> 1150320 WARN  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1150323 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1150353 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1150478 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1150485 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] 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> 1150506 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f2df7ed
   [junit4]   2> 1150506 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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.AutoCommitTest_814E8478C4B1CDE6-001/init-core-data-001/]
   [junit4]   2> 1150598 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1150598 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1150600 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6f808a9b[collection1] main]
   [junit4]   2> 1150601 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1150602 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1150606 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1150606 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1150606 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1150606 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1150606 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1150607 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1150607 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1150607 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1150607 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1150607 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1150609 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1724266285, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1150609 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1150611 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6f808a9b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1150611 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@65e3a410[collection1] main]
   [junit4]   2> 1150611 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1c9f51c7
   [junit4]   2> 1150612 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=480203207
   [junit4]   2> 1150620 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65e3a410[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1150642 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1566882716426698752)]} 0 9
   [junit4]   2> 1150642 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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> 1154633 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1154636 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4dfe9a29[collection1] main]
   [junit4]   2> 1154638 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4dfe9a29[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):C1)))}
   [junit4]   2> 1154638 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1154650 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1566882720639877120)]} 0 4018
   [junit4]   2> 1154650 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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> 1154651 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1566882720640925696)]} 0 4019
   [junit4]   2> 1154651 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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=1 status=0 QTime=0
   [junit4]   2> 1156651 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1156654 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12fb47a0[collection1] main]
   [junit4]   2> 1156656 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12fb47a0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.0.0):C1)))}
   [junit4]   2> 1156656 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1156904 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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> 1156904 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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=1 status=0 QTime=0
   [junit4]   2> 1156905 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723004416000)]} 0 6273
   [junit4]   2> 1156906 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723004416001)]} 0 6273
   [junit4]   2> 1156906 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723005464576)]} 0 6273
   [junit4]   2> 1156906 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723005464577)]} 0 6274
   [junit4]   2> 1156906 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723005464578)]} 0 6274
   [junit4]   2> 1156906 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723005464579)]} 0 6274
   [junit4]   2> 1156906 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723005464580)]} 0 6274
   [junit4]   2> 1156907 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723005464581)]} 0 6274
   [junit4]   2> 1156907 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723006513152)]} 0 6274
   [junit4]   2> 1156907 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1566882723006513153)]} 0 6275
   [junit4]   2> 1156907 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1156908 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723006513154)]} 0 6275
   [junit4]   2> 1156908 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723007561728)]} 0 6275
   [junit4]   2> 1156908 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723007561729)]} 0 6276
   [junit4]   2> 1156908 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723007561730)]} 0 6276
   [junit4]   2> 1156908 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723007561731)]} 0 6276
   [junit4]   2> 1156908 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723007561732)]} 0 6276
   [junit4]   2> 1156908 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723007561733)]} 0 6276
   [junit4]   2> 1156908 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723007561734)]} 0 6276
   [junit4]   2> 1156909 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723008610304)]} 0 6276
   [junit4]   2> 1156909 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1566882723008610305)]} 0 6276
   [junit4]   2> 1156909 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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=1 status=0 QTime=0
   [junit4]   2> 1157908 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1157919 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@71c4178f[collection1] main]
   [junit4]   2> 1157920 INFO  (commitScheduler-5278-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1157922 INFO  (searcherExecutor-5275-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71c4178f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(7.0.0):C10/9:delGen=1)))}
   [junit4]   2> 1158160 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 1158161 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    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> 1158161 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[814E8478C4B1CDE6]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> 1158161 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1158161 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=909770219
   [junit4]   2> 1158161 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1158163 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1158164 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1158164 INFO  (SUITE-AutoCommitTest-seed#[814E8478C4B1CDE6]-worker) [    x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1158164 INFO  (coreCloseExecutor-5279-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@20c2c0c3
   [junit4]   2> 1158165 INFO  (coreCloseExecutor-5279-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1158165 INFO  (coreCloseExecutor-5279-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2d6e239c commitCommandVersion:0
   [junit4]   2> 1158166 INFO  (coreCloseExecutor-5279-thread-1-processing-x:collection1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=549634243
   [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.AutoCommitTest_814E8478C4B1CDE6-001
   [junit4]   2> May 09, 2017 2:10:11 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {field_t=PostingsFormat(name=Direct), multiDefault=BlockTreeOrds(blocksize=128), subject=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=Direct), text=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Memory), intDefault=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Lucene70), range_facet_l=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=358, maxMBSortInHeap=6.38747405451528, sim=RandomSimilarity(queryNorm=true): {}, locale=fr, timezone=Europe/Zagreb
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=4,threads=1,free=243145800,total=530579456
   [junit4]   2> NOTE: All tests run in this JVM: [TestHashQParserPlugin, DeleteStatusTest, SolrMetricManagerTest, ConfigSetsAPITest, CollectionReloadTest, TestFieldResource, OverseerRolesTest, TestBlendedInfixSuggestions, TestSolr4Spatial, TestFastWriter, TestCloudPseudoReturnFields, TestScoreJoinQPNoScore, TestJsonFacetRefinement, HdfsChaosMonkeyNothingIsSafeTest, TestPseudoReturnFields, MultiTermTest, DistributedFacetPivotSmallAdvancedTest, GraphQueryTest, XsltUpdateRequestHandlerTest, SignatureUpdateProcessorFactoryTest, TestCSVLoader, TestDynamicFieldCollectionResource, CursorPagingTest, PingRequestHandlerTest, TestRandomDVFaceting, TestJoin, SuggestComponentTest, TestHdfsCloudBackupRestore, TestSolrConfigHandlerConcurrent, PeerSyncWithIndexFingerprintCachingTest, InfoHandlerTest, VMParamsZkACLAndCredentialsProvidersTest, SmileWriterTest, DefaultValueUpdateProcessorTest, URLClassifyProcessorTest, TestTolerantUpdateProcessorRandomCloud, TestCorePropertiesReload, UUIDFieldTest, TestLeaderInitiatedRecoveryThread, PeerSyncTest, TestManagedResource, TestManagedSchemaThreadSafety, SolrCoreTest, ShufflingReplicaListTransformerTest, TestRebalanceLeaders, ReturnFieldsTest, TestMaxScoreQueryParser, SortSpecParsingTest, NodeMutatorTest, TestInPlaceUpdatesStandalone, ConjunctionSolrSpellCheckerTest, TestReplicationHandlerBackup, TestTestInjection, QueryEqualityTest, TestLazyCores, LeaderInitiatedRecoveryOnCommitTest, RequiredFieldsTest, BigEndianAscendingWordSerializerTest, TestRawTransformer, CustomCollectionTest, LeaderElectionIntegrationTest, PolyFieldTest, TestPayloadScoreQParserPlugin, AddSchemaFieldsUpdateProcessorFactoryTest, TestOmitPositions, TestSolrFieldCacheBean, TestOrdValues, TestSolrCloudWithKerberosAlt, TestExactSharedStatsCache, TestShortCircuitedRequests, TestJettySolrRunner, SecurityConfHandlerTest, TestSolrCloudSnapshots, ChangedSchemaMergeTest, TestBulkSchemaAPI, RegexBoostProcessorTest, TestNRTOpen, SolrGangliaReporterTest, TestApiFramework, HdfsRestartWhileUpdatingTest, HighlighterMaxOffsetTest, UninvertDocValuesMergePolicyTest, TestManagedSynonymFilterFactory, TestObjectReleaseTracker, TestAuthorizationFramework, TestSchemaVersionResource, TestSimpleTextCodec, TestCoreAdminApis, TestBlobHandler, SparseHLLTest, TestLFUCache, TestExceedMaxTermLength, TestSmileRequest, LargeFieldTest, DistributedQueryElevationComponentTest, PathHierarchyTokenizerFactoryTest, MinimalSchemaTest, MissingSegmentRecoveryTest, ClassificationUpdateProcessorFactoryTest, TestCollectionAPI, BasicZkTest, TestAnalyzedSuggestions, CurrencyFieldOpenExchangeTest, TestSubQueryTransformer, TestLMJelinekMercerSimilarityFactory, TestBadConfig, TestOnReconnectListenerSupport, EnumFieldTest, DeleteNodeTest, TestFieldCacheSort, PeerSyncReplicationTest, SearchHandlerTest, TestPathTrie, TestRangeQuery, TestMacroExpander, BasicDistributedZkTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, ShardRoutingTest, TestReplicationHandler, ZkSolrClientTest, AutoCommitTest]
   [junit4] Completed [368/709 (1!)] on J0 in 16.98s, 3 tests, 1 error <<< FAILURES!

[...truncated 44481 lines...]

Mime
View raw message