You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/05/25 21:27:29 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-9-ea+168) - Build
# 19708 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19708/
Java: 64bit/jdk-9-ea+168 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.update.AutoCommitTest.testMaxTime
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([D28A202C15C99102:487E5DCE8B530D3E]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:898)
at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:563)
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 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.base/java.lang.Thread.run(Thread.java:844)
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:891)
... 39 more
Build Log:
[...truncated 12119 lines...]
[junit4] Suite: org.apache.solr.update.AutoCommitTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.AutoCommitTest_D28A202C15C99102-001/init-core-data-001
[junit4] 2> 1023762 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 1023763 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1023763 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1023764 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1023781 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1023800 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1023844 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1023861 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26769d0e
[junit4] 2> 1023863 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26769d0e
[junit4] 2> 1023863 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26769d0e
[junit4] 2> 1023864 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1023876 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1023893 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1023940 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1023943 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1023943 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26769d0e
[junit4] 2> 1023943 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1023943 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.AutoCommitTest_D28A202C15C99102-001/init-core-data-001/]
[junit4] 2> 1023944 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=48.9560546875, floorSegmentMB=1.2392578125, forceMergeDeletesPctAllowed=16.716338421106094, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14873428588891074
[junit4] 2> 1023967 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1023967 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1023967 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1023967 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1023968 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=9.177734375, floorSegmentMB=0.349609375, forceMergeDeletesPctAllowed=5.0676651276895, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.47447671947246184
[junit4] 2> 1023968 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3b324ab8[collection1] main]
[junit4] 2> 1023968 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1023982 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1024037 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1024037 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1024037 INFO (coreLoadExecutor-3690-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568403947437162496
[junit4] 2> 1024037 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1024037 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1024037 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1024037 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1024038 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1024038 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1024038 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1024038 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1024039 INFO (searcherExecutor-3691-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3b324ab8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1024039 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1024040 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
[junit4] 2> 1024040 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1024041 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@422d7579[collection1] realtime]
[junit4] 2> 1024041 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] 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> 1024041 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 1024041 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1024054 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1024071 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1024131 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1024134 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1024142 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26769d0e
[junit4] 2> 1024142 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.AutoCommitTest_D28A202C15C99102-001/init-core-data-001/]
[junit4] 2> 1024178 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1024178 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1024179 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@385a677[collection1] main]
[junit4] 2> 1024179 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1024180 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1024181 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1024182 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568403947589206016
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1024182 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1024183 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=50, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3191768126493172]
[junit4] 2> 1024183 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@385a677[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1024184 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1024184 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@b9bee71[collection1] main]
[junit4] 2> 1024184 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@24d6a71
[junit4] 2> 1024184 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=38627953
[junit4] 2> 1024185 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@b9bee71[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1024189 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1568403947595497472)]} 0 0
[junit4] 2> 1024189 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1568403947596546048)]} 0 1
[junit4] 2> 1024189 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1568403947596546049)]} 0 1
[junit4] 2> 1024189 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1568403947596546050)]} 0 1
[junit4] 2> 1024189 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1568403947596546051)]} 0 1
[junit4] 2> 1024189 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1568403947596546052)]} 0 1
[junit4] 2> 1024189 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1568403947596546053)]} 0 1
[junit4] 2> 1024190 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1568403947596546054)]} 0 1
[junit4] 2> 1024190 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1568403947597594624)]} 0 1
[junit4] 2> 1024190 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1568403947597594625)]} 0 2
[junit4] 2> 1024190 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1568403947597594626)]} 0 2
[junit4] 2> 1024190 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1568403947597594627)]} 0 2
[junit4] 2> 1024190 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1568403947597594628)]} 0 2
[junit4] 2> 1024190 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1568403947597594629)]} 0 2
[junit4] 2> 1024190 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] 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> 1024191 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1568403947598643200)]} 0 3
[junit4] 2> 1024192 INFO (commitScheduler-3699-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 1024193 INFO (commitScheduler-3699-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3870dc42[collection1] main]
[junit4] 2> 1024193 INFO (commitScheduler-3699-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1024194 INFO (searcherExecutor-3696-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3870dc42[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c15)))}
[junit4] 2> 1024441 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1568403947860787200)]} 0 253
[junit4] 2> 1024442 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] 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=1 status=0 QTime=0
[junit4] 2> 1024442 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 1024442 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[D28A202C15C99102]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
[junit4] 2> 1024443 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
[junit4] 2> 1024443 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1024444 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@75ac34b3[collection1] realtime]
[junit4] 2> 1024444 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1024444 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 1024445 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1024486 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1024498 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1024552 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1024555 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1024561 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26769d0e
[junit4] 2> 1024561 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.AutoCommitTest_D28A202C15C99102-001/init-core-data-001/]
[junit4] 2> 1024585 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1024585 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1024586 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5aa13329[collection1] main]
[junit4] 2> 1024586 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1024587 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1024588 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1024588 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1024588 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1024588 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1024588 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1024588 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1024589 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1024589 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1024589 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1024589 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1024590 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=17.8076171875, floorSegmentMB=1.0078125, forceMergeDeletesPctAllowed=23.302659748894158, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.22876518689396397
[junit4] 2> 1024590 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1024590 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5aa13329[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1024590 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d6443db[collection1] main]
[junit4] 2> 1024591 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3ae824f5
[junit4] 2> 1024598 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=988292341
[junit4] 2> 1024599 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6d6443db[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1024601 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1568403948027510784)]} 0 0
[junit4] 2> 1024601 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1028601 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 1028602 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@774e8b5d[collection1] main]
[junit4] 2> 1028602 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1028603 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@774e8b5d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):c1)))}
[junit4] 2> 1028605 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1568403952227057664)]} 0 4004
[junit4] 2> 1028605 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1028605 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1568403952227057665)]} 0 4005
[junit4] 2> 1028605 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1030605 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 1030607 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1484282[collection1] main]
[junit4] 2> 1030607 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1030608 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1484282[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):c1)))}
[junit4] 2> 1030857 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1030857 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1030857 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954588450816)]} 0 6257
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499392)]} 0 6257
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499393)]} 0 6257
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499394)]} 0 6257
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499395)]} 0 6257
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499396)]} 0 6258
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499397)]} 0 6258
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499398)]} 0 6258
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499399)]} 0 6258
[junit4] 2> 1030858 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1568403954589499400)]} 0 6258
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547968)]} 0 6258
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547969)]} 0 6258
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547970)]} 0 6259
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547971)]} 0 6259
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547972)]} 0 6259
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547973)]} 0 6259
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547974)]} 0 6259
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547975)]} 0 6259
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547976)]} 0 6259
[junit4] 2> 1030859 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1568403954590547977)]} 0 6259
[junit4] 2> 1030860 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1031860 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 1031872 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@106a3807[collection1] main]
[junit4] 2> 1031873 INFO (commitScheduler-3703-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1031874 INFO (searcherExecutor-3700-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@106a3807[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):c10/9:delGen=1)))}
[junit4] 2> 1032116 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1032117 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] 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> 1032117 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[D28A202C15C99102]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
[junit4] 2> 1032118 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
[junit4] 2> 1032118 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1032118 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5dc63730[collection1] realtime]
[junit4] 2> 1032118 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] 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> 1032119 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 1032119 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1032139 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1032157 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1032271 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1032276 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1032288 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26769d0e
[junit4] 2> 1032288 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.AutoCommitTest_D28A202C15C99102-001/init-core-data-001/]
[junit4] 2> 1032352 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1032352 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1032366 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1807b1f1[collection1] main]
[junit4] 2> 1032366 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1032367 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1032369 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1032372 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=65.2919921875, floorSegmentMB=1.6611328125, forceMergeDeletesPctAllowed=26.306777083133454, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6772597787827193
[junit4] 2> 1032372 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1032372 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1032372 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1032372 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1032372 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1032373 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1032373 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1032373 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1032373 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1032373 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1032375 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1807b1f1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1032375 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@173ca224[collection1] main]
[junit4] 2> 1032375 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@804f25e
[junit4] 2> 1032376 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=134541918
[junit4] 2> 1032383 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1568403956187529216)]} 0 1
[junit4] 2> 1032383 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] 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> 1032385 INFO (searcherExecutor-3704-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@173ca224[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1032634 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1568403956450721792)]} 0 252
[junit4] 2> 1032634 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] 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> 1032634 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] 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> 1032634 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] 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:891)
[junit4] 2> at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base/java.lang.reflect.Method.invoke(Method.java:563)
[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 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1032635 INFO (TEST-AutoCommitTest.testMaxTime-seed#[D28A202C15C99102]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=D28A202C15C99102 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=kkj -Dtests.timezone=America/Grenada -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.52s J0 | AutoCommitTest.testMaxTime <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([D28A202C15C99102:487E5DCE8B530D3E]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:898)
[junit4] > at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:563)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[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:891)
[junit4] > ... 39 more
[junit4] 2> 1032636 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1032636 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1803054166
[junit4] 2> 1032636 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1032636 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1032637 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1032637 INFO (SUITE-AutoCommitTest-seed#[D28A202C15C99102]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1032638 INFO (coreCloseExecutor-3708-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@8728df
[junit4] 2> 1032638 INFO (coreCloseExecutor-3708-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 1032638 INFO (coreCloseExecutor-3708-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@782804b1 commitCommandVersion:0
[junit4] 2> 1032641 INFO (coreCloseExecutor-3708-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=8857823
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.update.AutoCommitTest_D28A202C15C99102-001
[junit4] 2> May 25, 2017 9:09:31 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true): {}, locale=kkj, timezone=America/Grenada
[junit4] 2> NOTE: Linux 4.8.0-52-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=8,threads=1,free=263894712,total=483917824
[junit4] 2> NOTE: All tests run in this JVM: [TestInPlaceUpdatesDistrib, DateMathParserTest, LeaderInitiatedRecoveryOnShardRestartTest, TestCodecSupport, JvmMetricsTest, TestAnalyzedSuggestions, OverseerTaskQueueTest, TestRequestStatusCollectionAPI, TestClassicSimilarityFactory, SolrSlf4jReporterTest, AliasIntegrationTest, CdcrReplicationHandlerTest, TestCloudManagedSchema, DocValuesMissingTest, RestartWhileUpdatingTest, TestSchemaVersionResource, TestRawTransformer, SmileWriterTest, QueryEqualityTest, HdfsBasicDistributedZkTest, SignatureUpdateProcessorFactoryTest, TestFieldCacheVsDocValues, JsonValidatorTest, FastVectorHighlighterTest, TestUseDocValuesAsStored2, TestSolrDeletionPolicy2, BooleanFieldTest, TestNumericTerms32, WrapperMergePolicyFactoryTest, MinimalSchemaTest, TestCloudPivotFacet, TestSolrCloudWithKerberosAlt, SpellPossibilityIteratorTest, TestConfigSets, HdfsRecoveryZkTest, ResponseHeaderTest, HdfsCollectionsAPIDistributedZkTest, TestMacroExpander, TimeZoneUtilsTest, PingRequestHandlerTest, TestLockTree, TestSolrCLIRunExample, TestRuleBasedAuthorizationPlugin, TestFieldCacheReopen, TestRandomFaceting, TestJavabinTupleStreamParser, CollectionsAPISolrJTest, TestPathTrie, ReturnFieldsTest, PreAnalyzedFieldTest, RegexBoostProcessorTest, TestDocumentBuilder, TestPullReplicaErrorHandling, TestLRUStatsCache, TestSuggestSpellingConverter, ReplicaListTransformerTest, DistributedFacetPivotWhiteBoxTest, TestDistributedSearch, TestNestedDocsSort, TestLMJelinekMercerSimilarityFactory, TestFieldResource, TestJoin, TriLevelCompositeIdRoutingTest, SearchHandlerTest, TestQuerySenderListener, RemoteQueryErrorTest, DistributedSpellCheckComponentTest, ForceLeaderTest, HdfsDirectoryFactoryTest, CursorMarkTest, DefaultValueUpdateProcessorTest, ResponseLogComponentTest, CleanupOldIndexTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DeleteReplicaTest, TestJmxIntegration, BlockDirectoryTest, CacheHeaderTest, RequiredFieldsTest, TestSolrCoreProperties, TestManagedSchemaAPI, StandardRequestHandlerTest, LeaderFailoverAfterPartitionTest, RankQueryTest, TestShortCircuitedRequests, OverseerModifyCollectionTest, TestCrossCoreJoin, TestRecoveryHdfs, TestFunctionQuery, DocumentAnalysisRequestHandlerTest, TestPseudoReturnFields, TestMinMaxOnMultiValuedField, TestSolr4Spatial, TestSchemaManager, SyncSliceTest, TestSimpleQParserPlugin, ClassificationUpdateProcessorTest, VersionInfoTest, CurrencyFieldOpenExchangeTest, MultiTermTest, SolrGangliaReporterTest, TestPerFieldSimilarityWithDefaultOverride, TestMultiWordSynonyms, HdfsThreadLeakTest, TestStressInPlaceUpdates, SolrRequestParserTest, ZkNodePropsTest, TestDynamicFieldCollectionResource, UUIDFieldTest, TestPayloadCheckQParserPlugin, CollectionStateFormat2Test, PreAnalyzedFieldManagedSchemaCloudTest, TestTolerantUpdateProcessorCloud, ConnectionManagerTest, TestSubQueryTransformer, TestOmitPositions, BasicDistributedZk2Test, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, ClusterStateUpdateTest, ZkSolrClientTest, TestRandomDVFaceting, ZkCLITest, AutoCommitTest]
[junit4] Completed [366/714 (1!)] on J0 in 9.14s, 3 tests, 1 error <<< FAILURES!
[...truncated 37349 lines...]