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/10/13 19:08:29 UTC
[JENKINS] Lucene-Solr-7.1-Windows (64bit/jdk1.8.0_144) - Build # 1
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.1-Windows/1/
Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.search.TestAddFieldRealTimeGet
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001 C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001\tlog: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001\tlog C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001
C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001\tlog: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001\tlog
C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001
at __randomizedtesting.SeedInfo.seed([6D1247707E1B41AA]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
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:748)
FAILED: org.apache.solr.update.AutoCommitTest.testMaxTime
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([6D1247707E1B41AA:F7E63A92E081DD96]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:884)
at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:748)
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=&start=0&rows=20&version=2.2
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:877)
... 40 more
Build Log:
[...truncated 12127 lines...]
[junit4] Suite: org.apache.solr.update.AutoCommitTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_6D1247707E1B41AA-001\init-core-data-001
[junit4] 2> 902659 WARN (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 902660 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 902668 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 902668 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 902670 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 902671 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 902732 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 902752 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 902874 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 902981 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@434192be
[junit4] 2> 902985 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@434192be
[junit4] 2> 902985 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@434192be
[junit4] 2> 902988 INFO (coreLoadExecutor-3643-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 902989 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 903054 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 903073 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 903182 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 903192 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 903193 INFO (coreLoadExecutor-3643-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@434192be
[junit4] 2> 903193 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 903193 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_6D1247707E1B41AA-001\init-core-data-001\]
[junit4] 2> 903194 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=50.935546875, floorSegmentMB=0.509765625, forceMergeDeletesPctAllowed=6.028470419589917, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5311737838078232
[junit4] 2> 903274 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 903274 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 903276 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 903276 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 903277 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=10, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5112250995240551]
[junit4] 2> 903277 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b2cebe6[collection1] main]
[junit4] 2> 903278 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 903283 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 903309 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 903309 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 903310 INFO (coreLoadExecutor-3643-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1581166111952470016
[junit4] 2> 903310 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 903310 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 903310 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 903311 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 903311 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 903311 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 903311 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 903312 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 903312 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 903314 INFO (searcherExecutor-3644-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2b2cebe6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 903314 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
[junit4] 2> 903315 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 903315 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ea1d03a[collection1] realtime]
[junit4] 2> 903316 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] 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> 903316 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1\core.properties
[junit4] 2> 903317 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 903371 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 903401 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 903522 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 903537 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 903572 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ 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@434192be
[junit4] 2> 903572 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_6D1247707E1B41AA-001\init-core-data-001\]
[junit4] 2> 903659 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 903659 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 903661 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@63b0561e[collection1] main]
[junit4] 2> 903661 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 903665 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 903672 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 903672 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 903672 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1581166112332054528
[junit4] 2> 903674 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 903674 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 903674 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 903676 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 903677 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 903678 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 903678 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=30.02734375, floorSegmentMB=0.212890625, forceMergeDeletesPctAllowed=22.56515112732692, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 903678 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 903678 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 903681 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 903683 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@63b0561e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 903683 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d093a98[collection1] main]
[junit4] 2> 903684 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6ae67e82
[junit4] 2> 903684 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1793490562
[junit4] 2> 903684 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2ec0a87e: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@266d14a9
[junit4] 2> 903696 INFO (searcherExecutor-3649-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@d093a98[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 903700 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1581166112356171776)]} 0 6
[junit4] 2> 903700 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 903706 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1581166112367706112)]} 0 12
[junit4] 2> 903706 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 903706 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=&start=0&rows=20&version=2.2
[junit4] 2> 903707 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=id:529&qt=&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=&start=0&rows=20&version=2.2
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:877)
[junit4] 2> at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
[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:1737)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
[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:943)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
[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:748)
[junit4] 2>
[junit4] 2> 903707 INFO (TEST-AutoCommitTest.testMaxTime-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=6D1247707E1B41AA -Dtests.slow=true -Dtests.locale=mt-MT -Dtests.timezone=Brazil/West -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 0.40s J1 | AutoCommitTest.testMaxTime <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([6D1247707E1B41AA:F7E63A92E081DD96]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:884)
[junit4] > at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[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=&start=0&rows=20&version=2.2
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:877)
[junit4] > ... 40 more
[junit4] 2> 903710 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
[junit4] 2> 903710 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 903711 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@228f6ee1[collection1] realtime]
[junit4] 2> 903711 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ 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> 903711 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1\core.properties
[junit4] 2> 903712 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 903766 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 903787 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 903893 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 903902 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 903926 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ 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@434192be
[junit4] 2> 903926 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_6D1247707E1B41AA-001\init-core-data-001\]
[junit4] 2> 904023 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 904023 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 904024 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@50051f93[collection1] main]
[junit4] 2> 904025 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 904027 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 904032 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 904032 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 904033 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 904033 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 904033 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 904033 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 904033 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 904034 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 904034 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 904034 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ 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.5637407592123502]
[junit4] 2> 904034 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 904034 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 904038 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@50051f93[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 904038 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5307bfc5[collection1] main]
[junit4] 2> 904039 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@14dac7ae
[junit4] 2> 904039 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=349882286
[junit4] 2> 904039 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e66c8d1: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@266d14a9
[junit4] 2> 904041 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5307bfc5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 904049 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1581166112726319104)]} 0 0
[junit4] 2> 904049 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1581166112727367680)]} 0 1
[junit4] 2> 904050 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1581166112728416256)]} 0 1
[junit4] 2> 904050 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1581166112728416257)]} 0 1
[junit4] 2> 904050 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1581166112728416258)]} 0 2
[junit4] 2> 904050 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1581166112728416259)]} 0 2
[junit4] 2> 904050 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1581166112728416260)]} 0 2
[junit4] 2> 904051 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1581166112729464832)]} 0 2
[junit4] 2> 904051 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1581166112729464833)]} 0 2
[junit4] 2> 904051 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1581166112729464834)]} 0 2
[junit4] 2> 904051 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1581166112729464835)]} 0 3
[junit4] 2> 904051 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1581166112729464836)]} 0 3
[junit4] 2> 904052 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1581166112730513408)]} 0 3
[junit4] 2> 904052 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1581166112730513409)]} 0 3
[junit4] 2> 904052 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 904053 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1581166112731561984)]} 0 5
[junit4] 2> 904056 INFO (commitScheduler-3656-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> 904063 INFO (commitScheduler-3656-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ddaa979[collection1] main]
[junit4] 2> 904064 INFO (commitScheduler-3656-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 904066 INFO (searcherExecutor-3653-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ddaa979[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.1.0):c15)))}
[junit4] 2> 904304 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1581166112994754560)]} 0 256
[junit4] 2> 904305 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 904305 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 904306 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
[junit4] 2> 904309 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
[junit4] 2> 904309 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 904309 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6eacc753[collection1] realtime]
[junit4] 2> 904310 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ 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> 904311 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1\core.properties
[junit4] 2> 904312 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.1-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 904363 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 904381 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 904517 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 904526 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 904558 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ 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@434192be
[junit4] 2> 904558 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_6D1247707E1B41AA-001\init-core-data-001\]
[junit4] 2> 904646 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 904646 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 904647 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b7f6210[collection1] main]
[junit4] 2> 904648 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 904649 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 904654 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 904655 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 904656 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 904656 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 904656 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 904656 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 904656 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 904656 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=46.841796875, floorSegmentMB=1.0712890625, forceMergeDeletesPctAllowed=14.812357740471297, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8841432915033923
[junit4] 2> 904656 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 904656 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 904656 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 904657 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 904659 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5b7f6210[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 904659 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b3440d4[collection1] main]
[junit4] 2> 904660 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2eb3b2f4
[junit4] 2> 904660 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=783528692
[junit4] 2> 904660 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1693d272: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@266d14a9
[junit4] 2> 904662 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4b3440d4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 904670 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1581166113377484800)]} 0 0
[junit4] 2> 904670 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 908670 INFO (commitScheduler-3660-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> 908674 INFO (commitScheduler-3660-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17fe09a[collection1] main]
[junit4] 2> 908676 INFO (commitScheduler-3660-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 908678 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17fe09a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.1.0):c1)))}
[junit4] 2> 908680 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1581166117583323136)]} 0 4012
[junit4] 2> 908681 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 908682 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1581166117585420288)]} 0 4013
[junit4] 2> 908683 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 910700 INFO (commitScheduler-3660-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> 910703 INFO (commitScheduler-3660-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3dc8ce32[collection1] main]
[junit4] 2> 910703 INFO (commitScheduler-3660-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 910703 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3dc8ce32[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.1.0):c1)))}
[junit4] 2> 910725 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 910726 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 910758 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119744438272)]} 0 6090
[junit4] 2> 910761 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119763312640)]} 0 6091
[junit4] 2> 910761 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119765409792)]} 0 6092
[junit4] 2> 910761 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119765409793)]} 0 6092
[junit4] 2> 910762 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119765409794)]} 0 6092
[junit4] 2> 910763 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119767506944)]} 0 6094
[junit4] 2> 910763 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119767506945)]} 0 6094
[junit4] 2> 910763 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119767506946)]} 0 6094
[junit4] 2> 910764 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119767506947)]} 0 6094
[junit4] 2> 910764 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1581166119768555520)]} 0 6094
[junit4] 2> 910764 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 910765 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119769604096)]} 0 6096
[junit4] 2> 910765 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119769604097)]} 0 6096
[junit4] 2> 910767 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119770652672)]} 0 6097
[junit4] 2> 910767 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119771701248)]} 0 6097
[junit4] 2> 910767 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119771701249)]} 0 6098
[junit4] 2> 910768 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119771701250)]} 0 6099
[junit4] 2> 910768 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119772749824)]} 0 6099
[junit4] 2> 910775 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119780089856)]} 0 6106
[junit4] 2> 910775 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119780089857)]} 0 6107
[junit4] 2> 910784 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1581166119789527040)]} 0 6115
[junit4] 2> 910784 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 911770 INFO (commitScheduler-3660-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> 911780 INFO (commitScheduler-3660-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e77fd49[collection1] main]
[junit4] 2> 911780 INFO (commitScheduler-3660-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 911790 INFO (searcherExecutor-3657-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3e77fd49[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.1.0):c10/9:delGen=1)))}
[junit4] 2> 911808 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 911809 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 911810 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[6D1247707E1B41AA]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
[junit4] 2> 911811 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 911811 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=943635218
[junit4] 2> 911811 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 911811 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d25a1c4: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@c88f457
[junit4] 2> 911818 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 911818 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@733933f7: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@20546f01
[junit4] 2> 911831 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 911831 INFO (SUITE-AutoCommitTest-seed#[6D1247707E1B41AA]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3bb9a08b: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3fa15f0d
[junit4] 2> 911860 INFO (coreCloseExecutor-3661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6b86e2c7
[junit4] 2> 911860 INFO (coreCloseExecutor-3661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1804001991
[junit4] 2> 911860 INFO (coreCloseExecutor-3661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@18cab93a: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@266d14a9
[junit4] 2> 911874 INFO (coreCloseExecutor-3661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 911875 INFO (coreCloseExecutor-3661-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1a40cac3 commitCommandVersion:0
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_6D1247707E1B41AA-001
[junit4] 2> Oct 13, 2017 5:58:39 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=false): {}, locale=mt-MT, timezone=Brazil/West
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=232771792,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestOrdValues, OverseerTaskQueueTest, AutoAddReplicasIntegrationTest, ReplaceNodeTest, TestSolrXml, FileBasedSpellCheckerTest, CoreAdminRequestStatusTest, DistributedFacetPivotLongTailTest, TestSha256AuthenticationProvider, TestSimpleTextCodec, TestDistribIDF, LeaderElectionIntegrationTest, ZkNodePropsTest, TestMissingGroups, TestQueryUtils, RecoveryAfterSoftCommitTest, JSONWriterTest, FileUtilsTest, DOMUtilTest, TestPayloadCheckQParserPlugin, TestSuggestSpellingConverter, DistributedTermsComponentTest, SchemaVersionSpecificBehaviorTest, HdfsUnloadDistributedZkTest, OpenExchangeRatesOrgProviderTest, TestHttpShardHandlerFactory, SpellCheckCollatorTest, SuggesterFSTTest, TestPushWriter, TestDocumentBuilder, StressHdfsTest, SpellingQueryConverterTest, TestRecovery, SynonymTokenizerTest, TermVectorComponentDistributedTest, TestSolr4Spatial, AssignBackwardCompatibilityTest, HLLSerializationTest, TestConfigSets, TestPostingsSolrHighlighter, TestCustomStream, LeaderElectionContextKeyTest, CopyFieldTest, CdcrBootstrapTest, SegmentsInfoRequestHandlerTest, TestSchemaNameResource, HttpTriggerListenerTest, TestHalfAndHalfDocValues, TestComplexPhraseLeadingWildcard, TestOnReconnectListenerSupport, AsyncCallRequestStatusResponseTest, TestConfigSetsAPIExclusivity, ExternalFileFieldSortTest, HdfsRecoverLeaseTest, BasicZkTest, ManagedSchemaRoundRobinCloudTest, OpenCloseCoreStressTest, TestUseDocValuesAsStored2, TestTrieFacet, LukeRequestHandlerTest, TestZkChroot, SignatureUpdateProcessorFactoryTest, TestFieldCache, TestBinaryResponseWriter, DeleteInactiveReplicaTest, LargeFieldTest, JsonLoaderTest, QueryEqualityTest, TestSchemalessBufferedUpdates, IndexSchemaRuntimeFieldTest, TestInitParams, TestManagedResourceStorage, OverseerCollectionConfigSetProcessorTest, ResponseHeaderTest, TestReloadAndDeleteDocs, BasicAuthIntegrationTest, SolrJmxReporterTest, BadIndexSchemaTest, AutoscalingHistoryHandlerTest, TestPKIAuthenticationPlugin, TestJettySolrRunner, SolrIndexConfigTest, CdcrVersionReplicationTest, HdfsRestartWhileUpdatingTest, TestShortCircuitedRequests, BigEndianAscendingWordSerializerTest, BufferStoreTest, TestBulkSchemaConcurrent, LeaderFailureAfterFreshStartTest, SpellCheckComponentTest, RulesTest, SolrMetricManagerTest, DistributedQueryComponentCustomSortTest, TestSubQueryTransformer, AutoCommitTest]
[junit4] Completed [179/744 (1!)] on J1 in 9.50s, 3 tests, 1 error <<< FAILURES!
[...truncated 1682 lines...]
[junit4] Suite: org.apache.solr.search.TestAddFieldRealTimeGet
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001
[junit4] 2> 3844693 WARN (SUITE-TestAddFieldRealTimeGet-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 3844693 INFO (SUITE-TestAddFieldRealTimeGet-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 3844698 INFO (SUITE-TestAddFieldRealTimeGet-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 3844698 INFO (SUITE-TestAddFieldRealTimeGet-seed#[6D1247707E1B41AA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 3844699 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 3844709 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 3844806 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 3844827 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-id-and-version-fields-only.xml instead
[junit4] 2> 3844830 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.IndexSchema [null] Schema name=id-and-version-fields-only
[junit4] 2> 3844836 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.IndexSchema Loaded schema id-and-version-fields-only/1.6 with uniqueid field id
[junit4] 2> 3844837 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\tempDir-001\collection1\conf\managed-schema
[junit4] 2> 3844849 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\tempDir-001\collection1\conf\schema-id-and-version-fields-only.xml to C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\tempDir-001\collection1\conf\schema-id-and-version-fields-only.xml.bak
[junit4] 2> 3845014 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@434192be
[junit4] 2> 3845022 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@434192be
[junit4] 2> 3845022 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@434192be
[junit4] 2> 3845024 INFO (coreLoadExecutor-14468-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3845172 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 3845202 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=id-and-version-fields-only
[junit4] 2> 3845208 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema id-and-version-fields-only/1.6 with uniqueid field id
[junit4] 2> 3845209 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\tempDir-001\collection1, trusted=true
[junit4] 2> 3845210 INFO (coreLoadExecutor-14468-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@434192be
[junit4] 2> 3845210 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 3845210 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\tempDir-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001\]
[junit4] 2> 3845217 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=71.0703125, floorSegmentMB=0.517578125, forceMergeDeletesPctAllowed=23.509567867773, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7170127932168259
[junit4] 2> 3845248 WARN (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 3845353 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3845353 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3845355 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3845355 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3845356 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6894309343299944]
[junit4] 2> 3845356 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ffd0338[collection1] main]
[junit4] 2> 3845357 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\tempDir-001\collection1\conf
[junit4] 2> 3845358 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3845359 INFO (coreLoadExecutor-14468-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1581169196914442240
[junit4] 2> 3845359 INFO (searcherExecutor-14469-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4ffd0338[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3845366 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 3845366 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3845366 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@32d5c1b5[collection1] realtime]
[junit4] 2> 3845367 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] 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> 3845367 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1581169196922830848,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3845367 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@770d84a9 commitCommandVersion:1581169196922830848
[junit4] 2> 3845372 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5eb127e7[collection1] main]
[junit4] 2> 3845372 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3845375 INFO (searcherExecutor-14469-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5eb127e7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3845375 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 3845406 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{} 0 0
[junit4] 2> 3845416 ERROR (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: ERROR: [doc=1] unknown field 'newfield'
[junit4] 2> at org.apache.solr.update.DocumentBuilder.toDocument(DocumentBuilder.java:214)
[junit4] 2> at org.apache.solr.update.AddUpdateCommand.getLuceneDocument(AddUpdateCommand.java:100)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.updateDocument(DirectUpdateHandler2.java:958)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.updateDocOrDocValues(DirectUpdateHandler2.java:949)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:329)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:266)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:216)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:55)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:991)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1207)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:753)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
[junit4] 2> at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:261)
[junit4] 2> at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:188)
[junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2484)
[junit4] 2> at org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:125)
[junit4] 2> at org.apache.solr.util.TestHarness.update(TestHarness.java:284)
[junit4] 2> at org.apache.solr.util.BaseTestHarness.checkUpdateStatus(BaseTestHarness.java:281)
[junit4] 2> at org.apache.solr.util.BaseTestHarness.validateErrorUpdate(BaseTestHarness.java:264)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.checkUpdateU(SolrTestCaseJ4.java:834)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertFailedU(SolrTestCaseJ4.java:822)
[junit4] 2> at org.apache.solr.search.TestAddFieldRealTimeGet.test(TestAddFieldRealTimeGet.java:62)
[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:1737)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
[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:943)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
[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:748)
[junit4] 2>
[junit4] 2> 3845418 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\tempDir-001\collection1\conf\managed-schema
[junit4] 2> 3845429 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1581169196984696832)]} 0 3
[junit4] 2> 3845429 INFO (TEST-TestAddFieldRealTimeGet.test-seed#[6D1247707E1B41AA]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3845430 INFO (TEST-TestAddFieldRealTim
[...truncated too long message...]
tWhileUpdatingTest, TestShortCircuitedRequests, BigEndianAscendingWordSerializerTest, BufferStoreTest, TestBulkSchemaConcurrent, LeaderFailureAfterFreshStartTest, SpellCheckComponentTest, RulesTest, SolrMetricManagerTest, DistributedQueryComponentCustomSortTest, TestSubQueryTransformer, AutoCommitTest, TestTrackingShardHandlerFactory, TestClusterProperties, TestCloudRecovery, DistributedMLTComponentTest, LeaderInitiatedRecoveryOnShardRestartTest, TestTestInjection, BasicDistributedZk2Test, ClassificationUpdateProcessorFactoryTest, TestPolicyCloud, MoreLikeThisHandlerTest, TestDocBasedVersionConstraints, TestFastWriter, SolrCoreCheckLockOnStartupTest, TestMergePolicyConfig, DistribCursorPagingTest, ClassificationUpdateProcessorTest, WrapperMergePolicyFactoryTest, TestDistributedStatsComponentCardinality, DirectoryFactoryTest, TestSerializedLuceneMatchVersion, EchoParamsTest, PreAnalyzedFieldTest, TestLeaderElectionWithEmptyReplica, TestExportWriter, TestFastOutputStream, GraphQueryTest, TestCrossCoreJoin, TestRecoveryHdfs, TestScoreJoinQPScore, LoggingHandlerTest, ShufflingReplicaListTransformerTest, TestMultiValuedNumericRangeQuery, ExecutePlanActionTest, HighlighterMaxOffsetTest, PropertiesRequestHandlerTest, NodeLostTriggerTest, TestNonDefinedSimilarityFactory, TestSubQueryTransformerDistrib, CollectionsAPISolrJTest, DocExpirationUpdateProcessorFactoryTest, TestRandomFlRTGCloud, DocValuesMultiTest, TestReload, ActionThrottleTest, TestLRUCache, SolrGraphiteReporterTest, TestQueryWrapperFilter, HdfsBasicDistributedZk2Test, TestFieldTypeCollectionResource, TestFiltering, TestRealTimeGet, TestRTGBase, StatelessScriptUpdateProcessorFactoryTest, TestPerFieldSimilarity, TestLockTree, TestSolrCoreSnapshots, RestartWhileUpdatingTest, MissingSegmentRecoveryTest, TestLegacyField, TestAuthenticationFramework, TestRawTransformer, TestNumericTerms64, TestStressLiveNodes, TestAnalyzedSuggestions, TestCoreDiscovery, DeleteNodeTest, DistributedVersionInfoTest, SpellCheckCollatorWithCollapseTest, TestDistributedGrouping, DistribJoinFromCollectionTest, TestZkAclsWithHadoopAuth, SpellPossibilityIteratorTest, SuggesterTSTTest, BJQParserTest, SampleTest, DistributedFacetPivotSmallTest, TestApiFramework, TestMacroExpander, TestClassicSimilarityFactory, TestNestedDocsSort, PeerSyncWithIndexFingerprintCachingTest, ForceLeaderTest, CurrencyFieldTypeTest, TestPointFields, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OverseerTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TestFaceting, DistributedSpellCheckComponentTest, TestStressReorder, TestJoin, HardAutoCommitTest, TestRangeQuery, SimpleFacetsTest, StatsComponentTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, ConvertedLegacyTest, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, TestBadConfig, TestIndexSearcher, SoftAutoCommitTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, AnalysisAfterCoreReloadTest, CoreAdminHandlerTest, SolrRequestParserTest, TestFoldingMultitermQuery, TestStressLucene, TestTrie, SpatialFilterTest, TestCSVLoader, PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, TestRemoteStreaming, DistanceFunctionTest, TestSolrDeletionPolicy1, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, TestQueryTypes, TermsComponentTest, RequestHandlersTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, TestIndexingPerformance, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, FastVectorHighlighterTest, SolrPluginUtilsTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, MBeansHandlerTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, SOLR749Test, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, BadComponentTest, TestStressRecovery, TestSolrDeletionPolicy2, MultiTermTest, TestDocSet, TestBinaryField, TestSearchPerf, TestElisionMultitermQuery, TestConfig, TestSolrCoreProperties, DirectSolrConnectionTest, TestLuceneMatchVersion, TestCharFilters, TestXIncludeConfig, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest, ScriptEngineTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, TestSystemIdResolver, PrimUtilsTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestUtils, SliceStateTest, SystemInfoHandlerTest, CircularListTest, CursorPagingTest, DistributedIntervalFacetingTest, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestTolerantSearch, TestLuceneIndexBackCompat, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, CdcrReplicationHandlerTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CollectionReloadTest, CollectionStateFormat2Test, CollectionTooManyReplicasTest, CollectionsAPIAsyncDistributedZkTest, ConfigSetsAPITest, ConnectionManagerTest, CreateCollectionCleanupTest, CustomCollectionTest, DeleteShardTest, DeleteStatusTest, MoveReplicaHDFSTest, MultiThreadedOCPTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerModifyCollectionTest, OverseerRolesTest, RollingRestartTest, ShardSplitTest, TestSSLRandomization, TestSegmentSorting, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestStressInPlaceUpdates, TestTolerantUpdateProcessorCloud, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, VMParamsZkACLAndCredentialsProvidersTest, AutoScalingHandlerTest, NodeAddedTriggerTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest, HdfsSyncSliceTest, HdfsThreadLeakTest, HdfsTlogReplayBufferedWhileIndexingTest, ZkStateReaderTest, ZkStateWriterTest, ImplicitSnitchTest, CachingDirectoryFactoryTest, ConfigureRecoveryStrategyTest, CoreSorterTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, TestBackupRepositoryFactory, TestConfigSetImmutable, RequestLoggingTest, SearchHandlerTest, TestBlobHandler, TestConfigReload, TestSQLHandlerNonCloud, TestSolrConfigHandlerConcurrent, V2ApiIntegrationTest, V2StandaloneTest, CoreAdminCreateDiscoverTest, InfoHandlerTest, MetricsHandlerTest, SecurityConfHandlerTest, StatsReloadRaceTest, TestCollectionAPIs, TestConfigsApi, TestCoreAdminApis, DistributedDebugComponentTest, DistributedFacetExistsSmallTest, DistributedSuggestComponentTest, ReplicaListTransformerTest, SuggestComponentContextFilterQueryTest, TestExpandComponent, JavabinLoaderTest, TestSlowCompositeReaderWrapper, TestLegacyNumericUtils, TestLegacyTerms, TestNumericRangeQuery64, TestNumericTokenStream, MetricsConfigTest, SolrCoreMetricManagerTest, SolrMetricsIntegrationTest, SolrCloudReportersTest, SubstringBytesRefFilterTest, TestIntervalFaceting, TestDynamicFieldCollectionResource, TestDynamicFieldResource, TestFieldCollectionResource, TestFieldTypeResource, TestSchemaResource, TestSchemaSimilarityResource, TestManagedStopFilterFactory, BooleanFieldTest, ChangedSchemaMergeTest, SpatialRPTFieldTypeTest, TestCollationFieldDocValues, TestManagedSchemaAPI, TestSchemaManager, AnalyticsMergeStrategyTest, AnalyticsQueryTest, CursorMarkTest, RankQueryTest, SortSpecParsingTest, TestAddFieldRealTimeGet]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestAddFieldRealTimeGet -Dtests.seed=6D1247707E1B41AA -Dtests.slow=true -Dtests.locale=ar-KW -Dtests.timezone=Antarctica/McMurdo -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 0.00s J1 | TestAddFieldRealTimeGet (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001\tlog: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001\init-core-data-001\tlog
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.1-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestAddFieldRealTimeGet_6D1247707E1B41AA-001
[junit4] > at __randomizedtesting.SeedInfo.seed([6D1247707E1B41AA]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [672/744 (2!)] on J1 in 1.24s, 1 test, 1 error <<< FAILURES!
[...truncated 47204 lines...]