lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-7.2-Windows (64bit/jdk-9.0.1) - Build # 21 - Still Unstable!
Date Mon, 18 Dec 2017 08:38:54 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.2-Windows/21/
Java: 64bit/jdk-9.0.1 -XX:-UseCompressedOops -XX:+UseG1GC

3 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.lucene.index.TestBackwardsCompatibility

Error Message:
Could not remove the following files (in the order of attempts):    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_5EBDE555C2D18962-001\2.2.0-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_5EBDE555C2D18962-001\2.2.0-cfs-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_5EBDE555C2D18962-001\2.2.0-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_5EBDE555C2D18962-001\2.2.0-cfs-001

	at __randomizedtesting.SeedInfo.seed([5EBDE555C2D18962]: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.base/java.lang.Thread.run(Thread.java:844)


FAILED:  junit.framework.TestSuite.org.apache.solr.search.TestPayloadScoreQParserPlugin

Error Message:
Could not remove the following files (in the order of attempts):    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPayloadScoreQParserPlugin_AB3D707D62AD40CB-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPayloadScoreQParserPlugin_AB3D707D62AD40CB-001\init-core-data-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPayloadScoreQParserPlugin_AB3D707D62AD40CB-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPayloadScoreQParserPlugin_AB3D707D62AD40CB-001\init-core-data-001

	at __randomizedtesting.SeedInfo.seed([AB3D707D62AD40CB]: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.base/java.lang.Thread.run(Thread.java:844)


FAILED:  junit.framework.TestSuite.org.apache.solr.search.stats.TestExactSharedStatsCache

Error Message:
Could not remove the following files (in the order of attempts):    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard2\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard2\collection1    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard2    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard2\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard2\collection1
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard2: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard2
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001

	at __randomizedtesting.SeedInfo.seed([AB3D707D62AD40CB]: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.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 4600 lines...]
   [junit4] Suite: org.apache.lucene.index.TestBackwardsCompatibility
   [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateSingleSegmentNoCFS
   [junit4]    > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes
   [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateNoCFS
   [junit4]    > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes
   [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateSortedIndex
   [junit4]    > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes
   [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateCFS
   [junit4]    > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes
   [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateMoreTermsIndex
   [junit4]    > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes
   [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateIndexWithDocValuesUpdates
   [junit4]    > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes
   [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateSingleSegmentCFS
   [junit4]    > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes
   [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateEmptyIndex
   [junit4]    > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {content6=Lucene50(blocksize=128), content5=FST50, content4=PostingsFormat(name=Memory), trieInt=Lucene50(blocksize=128), content3=PostingsFormat(name=Direct), content2=Lucene50(blocksize=128), fie?ld=Lucene50(blocksize=128), utf8=Lucene50(blocksize=128), trieLong=FST50, id=PostingsFormat(name=Memory), content=Lucene50(blocksize=128), autf8=FST50}, docValues:{utf8=DocValuesFormat(name=Lucene70), fie?ld=DocValuesFormat(name=Lucene70), dvShort=DocValuesFormat(name=Memory), dvInt=DocValuesFormat(name=Lucene70), longPoint2d=DocValuesFormat(name=Direct), dvBytesSortedVar=DocValuesFormat(name=Lucene70), dvSortedSet=DocValuesFormat(name=Asserting), content=DocValuesFormat(name=Lucene70), floatPoint1d=DocValuesFormat(name=Asserting), intPoint1d=DocValuesFormat(name=Direct), doublePoint2d=DocValuesFormat(name=Lucene70), trieLong=DocValuesFormat(name=Memory), dvBytesDerefVar=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Asserting), binaryPoint2d=DocValuesFormat(name=Lucene70), dvSortedNumeric=DocValuesFormat(name=Direct), dvBytesDerefFixed=DocValuesFormat(name=Asserting), autf8=DocValuesFormat(name=Memory), dvBytesSortedFixed=DocValuesFormat(name=Memory), trieInt=DocValuesFormat(name=Lucene70), dvBytesStraightVar=DocValuesFormat(name=Direct), dvBytesStraightFixed=DocValuesFormat(name=Lucene70), dvLong=DocValuesFormat(name=Direct), longPoint1d=DocValuesFormat(name=Asserting), floatPoint2d=DocValuesFormat(name=Memory), dvDouble=DocValuesFormat(name=Asserting), dvFloat=DocValuesFormat(name=Memory), content6=DocValuesFormat(name=Lucene70), content5=DocValuesFormat(name=Memory), dvPacked=DocValuesFormat(name=Direct), intPoint2d=DocValuesFormat(name=Asserting), content4=DocValuesFormat(name=Asserting), content3=DocValuesFormat(name=Direct), content2=DocValuesFormat(name=Lucene70), doublePoint1d=DocValuesFormat(name=Direct), dvByte=DocValuesFormat(name=Direct), binaryPoint1d=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=472, maxMBSortInHeap=5.768875132487855, sim=RandomSimilarity(queryNorm=true): {content6=DFR I(n)LZ(0.3), content5=DFR I(F)LZ(0.3), content2=DFR I(ne)LZ(0.3), utf8=DFR I(ne)LZ(0.3), fie?ld=DFR I(ne)2, content=DFR G3(800.0), autf8=DFR I(n)B2}, locale=en-AI, timezone=Europe/Dublin
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.1 (64-bit)/cpus=3,threads=1,free=70783536,total=102760448
   [junit4]   2> NOTE: All tests run in this JVM: [TestLucene53NormsFormat, TestIndexWriterOnOldIndex, TestBackwardsCompatibility]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestBackwardsCompatibility -Dtests.seed=5EBDE555C2D18962 -Dtests.slow=true -Dtests.locale=en-AI -Dtests.timezone=Europe/Dublin -Dtests.asserts=true -Dtests.file.encoding=Cp1252
   [junit4] ERROR   0.00s J1 | TestBackwardsCompatibility (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.2-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_5EBDE555C2D18962-001\2.2.0-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_5EBDE555C2D18962-001\2.2.0-cfs-001
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([5EBDE555C2D18962]:0)
   [junit4]    > 	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    > 	at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4] Completed [5/8 (1!)] on J1 in 43.34s, 30 tests, 1 error, 8 skipped <<< FAILURES!

[...truncated 7904 lines...]
   [junit4] Suite: org.apache.solr.search.stats.TestExactSharedStatsCache
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\init-core-data-001
   [junit4]   2> 1098394 WARN  (SUITE-TestExactSharedStatsCache-seed#[AB3D707D62AD40CB]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 1098394 INFO  (SUITE-TestExactSharedStatsCache-seed#[AB3D707D62AD40CB]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1098397 INFO  (SUITE-TestExactSharedStatsCache-seed#[AB3D707D62AD40CB]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 1098397 INFO  (SUITE-TestExactSharedStatsCache-seed#[AB3D707D62AD40CB]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1098398 INFO  (SUITE-TestExactSharedStatsCache-seed#[AB3D707D62AD40CB]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1099274 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\cores\collection1
   [junit4]   2> 1099278 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1099278 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60f987bd{/,null,AVAILABLE}
   [junit4]   2> 1099284 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@33695f87{HTTP/1.1,[http/1.1]}{127.0.0.1:57050}
   [junit4]   2> 1099284 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.Server Started @1109345ms
   [junit4]   2> 1099284 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=57050, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\cores}
   [junit4]   2> 1099284 ERROR (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1099285 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 1099285 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1099285 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1099285 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-12-18T07:45:05.136381300Z
   [junit4]   2> 1099286 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\solr.xml
   [junit4]   2> 1099292 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 1099292 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1099294 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 1099368 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1099396 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1099396 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1099400 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\cores
   [junit4]   2> 1099400 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1099414 INFO  (coreLoadExecutor-5691-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 1099414 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1099476 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 1099525 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1099839 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1099866 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1, trusted=true
   [junit4]   2> 1099866 INFO  (coreLoadExecutor-5691-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@2f47567d
   [junit4]   2> 1099867 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1099867 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 1099869 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=33.05859375, floorSegmentMB=0.4736328125, forceMergeDeletesPctAllowed=24.35724194951056, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1100160 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1100160 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1100162 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1100162 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1100164 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=64.583984375, floorSegmentMB=1.3310546875, forceMergeDeletesPctAllowed=25.52355970070535, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1100170 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2782c56f[collection1] main]
   [junit4]   2> 1100170 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1100229 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1100497 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1100497 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1100498 INFO  (coreLoadExecutor-5691-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587106919323009024
   [junit4]   2> 1100499 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1100499 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1100499 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1100500 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1100501 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1100502 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1100507 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1100510 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1100510 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2782c56f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1101899 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\cores\collection1
   [junit4]   2> 1101902 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1101903 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@47e41914{/,null,AVAILABLE}
   [junit4]   2> 1101905 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@701b8c7b{HTTP/1.1,[http/1.1]}{127.0.0.1:57053}
   [junit4]   2> 1101906 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.Server Started @1111967ms
   [junit4]   2> 1101906 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=57053, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\cores}
   [junit4]   2> 1101906 ERROR (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1101906 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 1101906 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1101906 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1101906 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-12-18T07:45:07.757139900Z
   [junit4]   2> 1101906 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 1101912 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 1101912 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1101914 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 1101998 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1102031 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1102031 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1102034 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\cores
   [junit4]   2> 1102034 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1102046 INFO  (coreLoadExecutor-5702-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 1102047 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1102105 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 1102136 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1102281 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1102299 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1, trusted=true
   [junit4]   2> 1102299 INFO  (coreLoadExecutor-5702-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@2f47567d
   [junit4]   2> 1102300 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1102300 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 1102302 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=33.05859375, floorSegmentMB=0.4736328125, forceMergeDeletesPctAllowed=24.35724194951056, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1102442 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1102442 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1102454 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1102454 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1102454 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=64.583984375, floorSegmentMB=1.3310546875, forceMergeDeletesPctAllowed=25.52355970070535, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1102463 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c37c0fd[collection1] main]
   [junit4]   2> 1102464 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1102486 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1102660 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1102661 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1102661 INFO  (coreLoadExecutor-5702-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587106921591078912
   [junit4]   2> 1102664 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1102672 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1102672 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1102672 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1102675 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1102677 INFO  (qtp1697332431-11893) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1587106921598418944)} 0 8
   [junit4]   2> 1102682 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1102686 INFO  (qtp1770662671-11916) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1587106921611001856)} 0 6
   [junit4]   2> 1102690 INFO  (qtp1697332431-11904) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[0 (1587106921618341888)]} 0 3
   [junit4]   2> 1102691 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1102692 INFO  (qtp1770662671-11915) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[0 (1587106921623584768)]} 0 0
   [junit4]   2> 1102693 INFO  (qtp1697332431-11904) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1587106921624633344,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1102693 INFO  (qtp1697332431-11904) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@426a13d8 commitCommandVersion:1587106921624633344
   [junit4]   2> 1102721 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1102758 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1102760 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2c37c0fd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1102911 INFO  (qtp1697332431-11904) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@40171a24[collection1] main]
   [junit4]   2> 1102913 INFO  (qtp1697332431-11904) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1102915 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@40171a24[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C1)))}
   [junit4]   2> 1102915 INFO  (qtp1697332431-11904) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 222
   [junit4]   2> 1102916 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1587106921858465792,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1102916 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54b2829f commitCommandVersion:1587106921858465792
   [junit4]   2> 1102966 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@52a2b469[collection1] main]
   [junit4]   2> 1102967 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1102969 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@52a2b469[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C1)))}
   [junit4]   2> 1102969 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 52
   [junit4]   2> 1102972 INFO  (qtp1697332431-11899) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=a_t:one&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=1 status=0 QTime=1
   [junit4]   2> 1102991 INFO  (qtp1770662671-11917) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=http://127.0.0.1:57053//collection1&rid=127.0.0.1-collection1-1513583108824-5&version=2&q=a_t:one&requestPurpose=GET_TERM_STATS&NOW=1513583108824&isShard=true&wt=javabin&debugQuery=false} status=0 QTime=14
   [junit4]   2> 1102993 INFO  (qtp1770662671-11914) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&org.apache.solr.stats.termStats=a_t:on,1,1&shard.url=http://127.0.0.1:57053//collection1&rid=127.0.0.1-collection1-1513583108824-5&rows=10&version=2&org.apache.solr.stats.colStats=a_t,1,1,3,3&q=a_t:one&requestPurpose=GET_TOP_IDS,SET_TERM_STATS&NOW=1513583108824&isShard=true&org.apache.solr.stats.terms=a_t:on&wt=javabin&debugQuery=false} hits=1 status=0 QTime=0
   [junit4]   2> 1102995 INFO  (qtp1770662671-11916) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=http://127.0.0.1:57053//collection1&rid=127.0.0.1-collection1-1513583108824-5&version=2&q=a_t:one&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1513583108824&ids=0&isShard=true&wt=javabin&debugQuery=true} status=0 QTime=0
   [junit4]   2> 1102997 INFO  (qtp1770662671-11919) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=a_t:one&shards=http://127.0.0.1:57053//collection1&fl=*,score&wt=javabin&version=2&debugQuery=true} rid=127.0.0.1-collection1-1513583108824-5 hits=1 status=0 QTime=24
   [junit4]   1> ======================= Control Response =======================
   [junit4]   1> {responseHeader={status=0,QTime=1},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921618341888, intDvoDefault=42, score=0.57735026}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0=
   [junit4]   1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>   0.57735026 = fieldWeight in 0, product of:
   [junit4]   1>     1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>       1.0 = termFreq=1.0
   [junit4]   1>     1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>       1.0 = docFreq
   [junit4]   1>       1.0 = docCount
   [junit4]   1>     0.57735026 = fieldNorm(doc=0)
   [junit4]   1> },QParser=LuceneQParser,timing={time=1.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=1.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}
   [junit4]   1> 
   [junit4]   1> 
   [junit4]   1> ======================= Shard Response =======================
   [junit4]   1> 
   [junit4]   1> {responseHeader={status=0,QTime=24},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921623584768, intDvoDefault=42, score=0.57735026}]},debug={track={rid=127.0.0.1-collection1-1513583108824-5,PARSE_QUERY={http://127.0.0.1:57053//collection1={QTime=14,ElapsedTime=16,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=14},org.apache.solr.stats.terms=a_t:on,org.apache.solr.stats.termStats=a_t:on,1,1,org.apache.solr.stats.colStats=a_t,1,1,3,3,debug={timing={time=14.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=13.0,query={time=13.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},EXECUTE_QUERY={http://127.0.0.1:57053//collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=1,Response={responseHeader={status=0,QTime=0},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0, score=0.57735026}]},sort_values={},debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},GET_FIELDS={http://127.0.0.1:57053//collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=1,Response={responseHeader={status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921623584768, intDvoDefault=42, score=0.57735026}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0=
   [junit4]   1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>   0.57735026 = fieldWeight in 0, product of:
   [junit4]   1>     1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>       1.0 = termFreq=1.0
   [junit4]   1>     1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>       1.0 = docFreq
   [junit4]   1>       1.0 = docCount
   [junit4]   1>     0.57735026 = fieldNorm(doc=0)
   [junit4]   1> },QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}}},timing={time=14.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=13.0,query={time=13.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}},rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,QParser=LuceneQParser,explain={0=
   [junit4]   1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>   0.57735026 = fieldWeight in 0, product of:
   [junit4]   1>     1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>       1.0 = termFreq=1.0
   [junit4]   1>     1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>       1.0 = docFreq
   [junit4]   1>       1.0 = docCount
   [junit4]   1>     0.57735026 = fieldNorm(doc=0)
   [junit4]   1> }}}
   [junit4]   2> 1103006 INFO  (qtp1697332431-11898) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[1 (1587106921949691904)]} 0 2
   [junit4]   2> 1103008 INFO  (qtp1770662671-11915) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={wt=javabin&version=2}{add=[1 (1587106921952837632)]} 0 1
   [junit4]   2> 1103009 INFO  (qtp1697332431-11897) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1587106921955983360,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1103009 INFO  (qtp1697332431-11897) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@426a13d8 commitCommandVersion:1587106921955983360
   [junit4]   2> 1103030 INFO  (qtp1697332431-11897) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@64934f59[collection1] main]
   [junit4]   2> 1103031 INFO  (qtp1697332431-11897) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1103033 INFO  (searcherExecutor-5692-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64934f59[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C1) Uninverting(_1(7.2.0):C1)))}
   [junit4]   2> 1103033 INFO  (qtp1697332431-11897) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 23
   [junit4]   2> 1103034 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1587106921982197760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1103034 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54b2829f commitCommandVersion:1587106921982197760
   [junit4]   2> 1103059 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@32a1edf0[collection1] main]
   [junit4]   2> 1103059 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1103062 INFO  (searcherExecutor-5703-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@32a1edf0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C1) Uninverting(_1(7.2.0):C1)))}
   [junit4]   2> 1103063 INFO  (qtp1770662671-11920) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 28
   [junit4]   2> 1103065 INFO  (qtp1697332431-11896) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=a_t:one+a_t:four&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=2 status=0 QTime=1
   [junit4]   2> 1103067 INFO  (qtp1770662671-11917) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=http://127.0.0.1:57053//collection1&rid=127.0.0.1-collection1-1513583108917-6&version=2&q=a_t:one+a_t:four&requestPurpose=GET_TERM_STATS&NOW=1513583108917&isShard=true&wt=javabin&debugQuery=false} status=0 QTime=0
   [junit4]   2> 1103069 INFO  (qtp1770662671-11914) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&org.apache.solr.stats.termStats=a_t:on,2,2!a_t:four,1,1&shard.url=http://127.0.0.1:57053//collection1&rid=127.0.0.1-collection1-1513583108917-6&rows=10&version=2&org.apache.solr.stats.colStats=a_t,2,2,8,8&q=a_t:one+a_t:four&requestPurpose=GET_TOP_IDS,SET_TERM_STATS&NOW=1513583108917&isShard=true&org.apache.solr.stats.terms=a_t:on&org.apache.solr.stats.terms=a_t:four&wt=javabin&debugQuery=false} hits=2 status=0 QTime=0
   [junit4]   2> 1103071 INFO  (qtp1770662671-11916) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=http://127.0.0.1:57053//collection1&rid=127.0.0.1-collection1-1513583108917-6&version=2&q=a_t:one+a_t:four&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1513583108917&ids=0,1&isShard=true&wt=javabin&debugQuery=true} status=0 QTime=0
   [junit4]   2> 1103079 INFO  (qtp1770662671-11918) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=a_t:one+a_t:four&shards=http://127.0.0.1:57053//collection1&fl=*,score&wt=javabin&version=2&debugQuery=true} rid=127.0.0.1-collection1-1513583108917-6 hits=2 status=0 QTime=13
   [junit4]   1> ======================= Control Response =======================
   [junit4]   1> {responseHeader={status=0,QTime=1},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1, range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921949691904, intDvoDefault=42, score=1.0757567}, SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921618341888, intDvoDefault=42, score=0.57735026}]},debug={rawquerystring=a_t:one a_t:four,querystring=a_t:one a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on a_t:four,explain={1=
   [junit4]   1> 1.0757567 = sum of:
   [junit4]   1>   0.4472136 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.4472136 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         2.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.4472136 = fieldNorm(doc=0)
   [junit4]   1>   0.6285431 = weight(a_t:four in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.6285431 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.4054651 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         1.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.4472136 = fieldNorm(doc=0)
   [junit4]   1> ,0=
   [junit4]   1> 0.57735026 = sum of:
   [junit4]   1>   0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.57735026 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         2.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.57735026 = fieldNorm(doc=0)
   [junit4]   1> },QParser=LuceneQParser,timing={time=1.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=1.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}
   [junit4]   1> 
   [junit4]   1> 
   [junit4]   1> ======================= Shard Response =======================
   [junit4]   1> 
   [junit4]   1> {responseHeader={status=0,QTime=13},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1, range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921952837632, intDvoDefault=42, score=1.0757567}, SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921623584768, intDvoDefault=42, score=0.57735026}]},debug={track={rid=127.0.0.1-collection1-1513583108917-6,PARSE_QUERY={http://127.0.0.1:57053//collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=0},org.apache.solr.stats.terms=a_t:on,org.apache.solr.stats.terms=a_t:four,org.apache.solr.stats.termStats=a_t:on,2,2!a_t:four,1,1,org.apache.solr.stats.colStats=a_t,2,2,8,8,debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},EXECUTE_QUERY={http://127.0.0.1:57053//collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=2,Response={responseHeader={status=0,QTime=0},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1, score=1.0757567}, SolrDocument{id=0, score=0.57735026}]},sort_values={},debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},GET_FIELDS={http://127.0.0.1:57053//collection1={QTime=0,ElapsedTime=8,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=2,Response={responseHeader={status=0,QTime=0},response={numFound=2,start=0,docs=[SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921623584768, intDvoDefault=42, score=0.57735026}, SolrDocument{id=1, range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Mon Dec 18 08:45:08 CET 2017, _version_=1587106921952837632, intDvoDefault=42, score=1.0757567}]},debug={rawquerystring=a_t:one a_t:four,querystring=a_t:one a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on a_t:four,explain={0=
   [junit4]   1> 0.57735026 = sum of:
   [junit4]   1>   0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.57735026 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         2.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.57735026 = fieldNorm(doc=0)
   [junit4]   1> ,1=
   [junit4]   1> 1.0757567 = sum of:
   [junit4]   1>   0.4472136 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.4472136 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         2.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.4472136 = fieldNorm(doc=0)
   [junit4]   1>   0.6285431 = weight(a_t:four in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.6285431 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.4054651 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         1.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.4472136 = fieldNorm(doc=0)
   [junit4]   1> },QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}}},timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}},rawquerystring=a_t:one a_t:four,querystring=a_t:one a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on a_t:four,QParser=LuceneQParser,explain={1=
   [junit4]   1> 1.0757567 = sum of:
   [junit4]   1>   0.4472136 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.4472136 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         2.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.4472136 = fieldNorm(doc=0)
   [junit4]   1>   0.6285431 = weight(a_t:four in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.6285431 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.4054651 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         1.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.4472136 = fieldNorm(doc=0)
   [junit4]   1> ,0=
   [junit4]   1> 0.57735026 = sum of:
   [junit4]   1>   0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of:
   [junit4]   1>     0.57735026 = fieldWeight in 0, product of:
   [junit4]   1>       1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>         1.0 = termFreq=1.0
   [junit4]   1>       1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>         2.0 = docFreq
   [junit4]   1>         2.0 = docCount
   [junit4]   1>       0.57735026 = fieldNorm(doc=0)
   [junit4]   1> }}}
   [junit4]   2> 1103081 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 1103083 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@33695f87{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1103084 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=481990884
   [junit4]   2> 1103084 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1103084 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35aefc7c: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2602c9fa
   [junit4]   2> 1103090 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1103091 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a0d6977: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2b2bd927
   [junit4]   2> 1103095 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1103096 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13b763f1: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2877d4d3
   [junit4]   2> 1103096 INFO  (coreCloseExecutor-5708-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@65d2f8be
   [junit4]   2> 1103096 INFO  (coreCloseExecutor-5708-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1708325054
   [junit4]   2> 1103096 INFO  (coreCloseExecutor-5708-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19e92b68: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@72d85862
   [junit4]   2> 1103117 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@60f987bd{/,null,UNAVAILABLE}
   [junit4]   2> 1103120 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@701b8c7b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1103120 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=606454386
   [junit4]   2> 1103120 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1103120 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c81ada9: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6b223fde
   [junit4]   2> 1103129 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1103129 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1de101cb: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2b2bd927
   [junit4]   2> 1103136 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1103136 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ec7ad2c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2877d4d3
   [junit4]   2> 1103140 INFO  (coreCloseExecutor-5710-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3416aef8
   [junit4]   2> 1103141 INFO  (coreCloseExecutor-5710-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=873901816
   [junit4]   2> 1103141 INFO  (coreCloseExecutor-5710-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@978b7e7: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@d35db96
   [junit4]   2> 1103167 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@47e41914{/,null,UNAVAILABLE}
   [junit4]   2> 1103746 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1103747 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@54b8ad3c{/,null,AVAILABLE}
   [junit4]   2> 1103748 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@6c6447e3{HTTP/1.1,[http/1.1]}{127.0.0.1:57059}
   [junit4]   2> 1103748 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.Server Started @1113809ms
   [junit4]   2> 1103748 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=57059, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\cores}
   [junit4]   2> 1103749 ERROR (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1103749 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 1103749 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1103749 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1103749 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-12-18T07:45:09.600214200Z
   [junit4]   2> 1103750 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\solr.xml
   [junit4]   2> 1103755 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 1103755 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1103757 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 1103851 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1103866 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1103868 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1103870 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\cores
   [junit4]   2> 1103870 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1103898 INFO  (coreLoadExecutor-5717-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 1103899 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1103968 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 1104044 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1104305 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1104340 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1, trusted=true
   [junit4]   2> 1104341 INFO  (coreLoadExecutor-5717-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@2f47567d
   [junit4]   2> 1104341 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1104341 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 1104346 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=33.05859375, floorSegmentMB=0.4736328125, forceMergeDeletesPctAllowed=24.35724194951056, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1104526 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1104526 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1104528 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1104528 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1104530 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=64.583984375, floorSegmentMB=1.3310546875, forceMergeDeletesPctAllowed=25.52355970070535, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1104531 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c8a405a[collection1] main]
   [junit4]   2> 1104532 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1104534 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1104545 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1104546 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1104547 INFO  (coreLoadExecutor-5717-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587106923568693248
   [junit4]   2> 1104550 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1104550 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1104550 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1104551 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1104552 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1104575 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1104588 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1104590 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1104594 INFO  (searcherExecutor-5718-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c8a405a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1105340 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1105341 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@42baf9c6{/,null,AVAILABLE}
   [junit4]   2> 1105342 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@2d4574a3{HTTP/1.1,[http/1.1]}{127.0.0.1:57062}
   [junit4]   2> 1105342 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.e.j.s.Server Started @1115403ms
   [junit4]   2> 1105343 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=57062, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\cores}
   [junit4]   2> 1105343 ERROR (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1105343 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.2.0
   [junit4]   2> 1105343 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 1105343 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1105343 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-12-18T07:45:11.194042800Z
   [junit4]   2> 1105344 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 1105351 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 1105351 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1105353 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d, but no JMX reporters were configured - adding default JMX reporter.
   [junit4]   2> 1105442 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1105459 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1105459 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f47567d
   [junit4]   2> 1105464 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\cores
   [junit4]   2> 1105464 INFO  (TEST-TestExactSharedStatsCache.test-seed#[AB3D707D62AD40CB]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1105476 INFO  (coreLoadExecutor-5728-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
   [junit4]   2> 1105477 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1105575 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 1105619 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1105815 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1105837 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1, trusted=true
   [junit4]   2> 1105838 INFO  (coreLoadExecutor-5728-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@2f47567d
   [junit4]   2> 1105838 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1105839 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactSharedStatsCache_AB3D707D62AD40CB-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 1105841 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=33.05859375, floorSegmentMB=0.4736328125, forceMergeDeletesPctAllowed=24.35724194951056, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1105988 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1105988 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1106003 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1106004 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1106005 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=64.583984375, floorSegmentMB=1.3310546875, forceMergeDeletesPctAllowed=25.52355970070535, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1106006 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3cfc74dd[collection1] main]
   [junit4]   2> 1106008 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1106011 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1106034 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1106034 INFO  (searcherExecutor-5729-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1106035 INFO  (coreLoadExecutor-5728-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587106925128974336
   [junit4]   2> 1106038 INFO  (searcherExecutor-5729-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1106038 INFO  (searcherExecutor-5729-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1106038 INFO  (searcherExecutor-5729-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1106040 INFO  (searcherExecutor-5729-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1106046 INFO  (searcherExecutor-5729-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1106047 INFO  (searcherExecutor-5729-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker

[...truncated too long message...]

estCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {id=FSTOrd50, vals_dpf=PostingsFormat(name=MockRandom)}, docValues:{}, maxPointsInLeafNode=259, maxMBSortInHeap=5.22423598834065, sim=RandomSimilarity(queryNorm=false): {}, locale=zu-ZA, timezone=Pacific/Chatham
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.1 (64-bit)/cpus=3,threads=1,free=171422424,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [ShowFileRequestHandlerTest, TestSQLHandler, TestSchemalessBufferedUpdates, ConfigSetsAPITest, TestImplicitCoreProperties, TestDownShardTolerantSearch, MoveReplicaTest, RequestHandlersTest, TestHashQParserPlugin, TestDistributedSearch, SolrJmxReporterCloudTest, RulesTest, TestConfigSetsAPI, TestFieldCache, TestSizeLimitedDistributedMap, TestTolerantUpdateProcessorRandomCloud, UniqFieldsUpdateProcessorFactoryTest, UUIDFieldTest, HighlighterConfigTest, DisMaxRequestHandlerTest, TestLegacyTerms, TestBulkSchemaAPI, TestMaxTokenLenTokenizer, CollectionsAPISolrJTest, JavabinLoaderTest, ShardRoutingTest, SuggesterTest, SolrRequestParserTest, WordBreakSolrSpellCheckerTest, SubstringBytesRefFilterTest, TestFieldCollectionResource, TestLFUCache, TestStressRecovery, StressHdfsTest, TestCloudPseudoReturnFields, TestRecovery, FacetPivotSmallTest, TestEmbeddedSolrServerAdminHandler, IndexSchemaTest, CleanupOldIndexTest, DistanceFunctionTest, TestUseDocValuesAsStored2, OverseerModifyCollectionTest, TestSolrConfigHandlerCloud, TestClassicSimilarityFactory, DocValuesMissingTest, ClassificationUpdateProcessorTest, TestSolrCoreParser, QueryParsingTest, RecoveryZkTest, TestGraphMLResponseWriter, TestImpersonationWithHadoopAuth, TestTestInjection, TestCloudJSONFacetJoinDomain, SolrTestCaseJ4Test, TestHighlightDedupGrouping, TestNoOpRegenerator, TestFieldCacheSort, TestJmxIntegration, DistributedVersionInfoTest, BooleanFieldTest, BlockJoinFacetRandomTest, TestPushWriter, TestReloadAndDeleteDocs, TestLocalFSCloudBackupRestore, SliceStateTest, CursorPagingTest, IndexBasedSpellCheckerTest, GraphQueryTest, TestMissingGroups, TestLazyCores, NoCacheHeaderTest, TestSmileRequest, ForceLeaderTest, RequiredFieldsTest, TestNumericRangeQuery32, BasicAuthIntegrationTest, SolrInfoBeanTest, RAMDirectoryFactoryTest, SaslZkACLProviderTest, NotRequiredUniqueKeyTest, JvmMetricsTest, TestAddFieldRealTimeGet, TestDeleteCollectionOnDownNodes, TestFuzzyAnalyzedSuggestions, TestSolrCloudWithSecureImpersonation, HdfsRestartWhileUpdatingTest, TestSchemaNameResource, VersionInfoTest, SolrMetricReporterTest, SpellCheckCollatorTest, PKIAuthenticationIntegrationTest, DefaultValueUpdateProcessorTest, TestRTimerTree, TestQueryWrapperFilter, SpellCheckCollatorWithCollapseTest, TestPhraseSuggestions, ClassificationUpdateProcessorFactoryTest, AssignBackwardCompatibilityTest, NodeMutatorTest, ManagedSchemaRoundRobinCloudTest, SuggestComponentTest, HdfsUnloadDistributedZkTest, MoveReplicaHDFSFailoverTest, TestHalfAndHalfDocValues, BasicDistributedZkTest, AlternateDirectoryTest, TestSurroundQueryParser, RollingRestartTest, ConvertedLegacyTest, SharedFSAutoReplicaFailoverTest, TestConfigSetsAPIZkFailure, LeaderInitiatedRecoveryOnShardRestartTest, TlogReplayBufferedWhileIndexingTest, ConnectionReuseTest, TestManagedResourceStorage, RankQueryTest, PeerSyncWithIndexFingerprintCachingTest, TriggerIntegrationTest, ResponseLogComponentTest, TestSearcherReuse, TestQuerySenderListener, HighlighterTest, SolrCoreMetricManagerTest, PrimUtilsTest, CdcrReplicationDistributedZkTest, MinimalSchemaTest, SuggesterTSTTest, TestSimpleTextCodec, TestRealTimeGet, TestTolerantSearch, TestPivotHelperCode, TestHdfsUpdateLog, TestSha256AuthenticationProvider, DirectUpdateHandlerTest, TestDistribIDF, TestNonDefinedSimilarityFactory, NodeLostTriggerTest, TestManagedSynonymGraphFilterFactory, HdfsDirectoryFactoryTest, AutoScalingHandlerTest, HdfsChaosMonkeyNothingIsSafeTest, TestFieldCacheVsDocValues, SuggesterWFSTTest, TestBulkSchemaConcurrent, SpatialFilterTest, CdcrRequestHandlerTest, TestLeaderElectionZkExpiry, LeaderFailureAfterFreshStartTest, TestCoreAdminApis, HttpTriggerListenerTest, TestConfigSets, SparseHLLTest, TestSolrXml, SolrCmdDistributorTest, HLLSerializationTest, CoreAdminCreateDiscoverTest, URLClassifyProcessorTest, ResponseHeaderTest, TestNumericTokenStream, TestCustomSort, TestScoreJoinQPScore, AtomicUpdateProcessorFactoryTest, TestReqParamsAPI, TestCoreDiscovery, SolrXmlInZkTest, ExecutePlanActionTest, TestReplicaProperties, DirectSolrConnectionTest, TestUnifiedSolrHighlighter, SpellCheckComponentTest, RegexBoostProcessorTest, TestFilteredDocIdSet, TestBinaryResponseWriter, ReplicaListTransformerTest, TestBlendedInfixSuggestions, ZkControllerTest, DistribJoinFromCollectionTest, DirectUpdateHandlerOptimizeTest, CollectionReloadTest, OverseerStatusTest, DistributedQueryComponentOptimizationTest, TestManagedSchemaAPI, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, BasicZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, LeaderElectionTest, ZkSolrClientTest, ZkCLITest, TermVectorComponentDistributedTest, TestReload, DistributedTermsComponentTest, TestSolr4Spatial, StatsComponentTest, BadIndexSchemaTest, TestFiltering, TestFunctionQuery, DistributedQueryElevationComponentTest, SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, TestCSVLoader, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, SortByFunctionTest, DebugComponentTest, TestQueryUtils, TestWriterPerf, TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, TestOmitPositions, XmlUpdateRequestHandlerTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, UpdateRequestProcessorFactoryTest, MBeansHandlerTest, ScriptEngineTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, AsyncCallRequestStatusResponseTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CollectionsAPIAsyncDistributedZkTest, DeleteShardTest, DistribDocExpirationUpdateProcessorTest, DocValuesNotIndexedTest, LegacyCloudClusterPropTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, PeerSyncReplicationTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, TestAuthenticationFramework, TestCloudDeleteByQuery, TestCloudSearcherWarming, TestCollectionAPI, TestHdfsCloudBackupRestore, TestLeaderElectionWithEmptyReplica, TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudClusterSSL, TestOnReconnectListenerSupport, TestPrepRecovery, TestRandomRequestDistribution, TestRebalanceLeaders, TestRequestForwarding, TestRequestStatusCollectionAPI, TestSSLRandomization, TestSegmentSorting, TestSkipOverseerOperations, TestStressInPlaceUpdates, AutoAddReplicasIntegrationTest, SystemLogListenerTest, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, HdfsRecoverLeaseTest, HdfsSyncSliceTest, HdfsThreadLeakTest, HdfsTlogReplayBufferedWhileIndexingTest, HdfsWriteToMultipleCollectionsTest, ZkStateReaderTest, ImplicitSnitchTest, RuleEngineTest, ConfigureRecoveryStrategyTest, DirectoryFactoryTest, QueryResultKeyTest, TestBackupRepositoryFactory, TestConfigSetImmutable, TestCorePropertiesReload, TestCustomStream, TestInitParams, TestNRTOpen, TestSolrCoreSnapshots, RequestLoggingTest, SearchHandlerTest, TestBlobHandler, TestCoreBackup, TestHdfsBackupRestoreCore, TestReplicationHandlerBackup, TestSolrConfigHandlerConcurrent, V2StandaloneTest, AutoscalingHistoryHandlerTest, InfixSuggestersTest, SuggestComponentContextFilterQueryTest, TestExpandComponent, HighlighterMaxOffsetTest, TestSlowCompositeReaderWrapper, UninvertDocValuesMergePolicyTest, CheckHdfsIndexTest, TestLegacyField, TestLegacyFieldReuse, MetricsConfigTest, SolrMetricManagerTest, SolrGangliaReporterTest, SolrGraphiteReporterTest, SolrSlf4jReporterTest, SolrShardReporterTest, TestStreamBody, TestMacroExpander, JSONWriterTest, SmileWriterTest, TestExportWriter, TestJavabinTupleStreamParser, TestRawTransformer, TestRetrieveFieldsOptimizer, TestSolrQueryResponse, TestSubQueryTransformer, TestSubQueryTransformerCrossCore, TestSubQueryTransformerDistrib, SpatialRPTFieldTypeTest, TestCollationFieldDocValues, TestManagedSchemaThreadSafety, TestPointFields, TestUseDocValuesAsStored, AnalyticsQueryTest, CursorMarkTest, LargeFieldTest, MergeStrategyTest, TestComplexPhraseLeadingWildcard, TestComplexPhraseQParserPlugin, TestGraphTermsQParserPlugin, TestLegacyNumericRangeQueryBuilder, TestMaxScoreQueryParser, TestOverriddenPrefixQueryForCustomFieldType, TestPayloadCheckQParserPlugin, TestPayloadScoreQParserPlugin]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestPayloadScoreQParserPlugin -Dtests.seed=AB3D707D62AD40CB -Dtests.slow=true -Dtests.locale=zu-ZA -Dtests.timezone=Pacific/Chatham -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | TestPayloadScoreQParserPlugin (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.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPayloadScoreQParserPlugin_AB3D707D62AD40CB-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPayloadScoreQParserPlugin_AB3D707D62AD40CB-001\init-core-data-001
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([AB3D707D62AD40CB]:0)
   [junit4]    > 	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    > 	at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4] Completed [688/751 (2!)] on J0 in 0.48s, 1 test, 1 error <<< FAILURES!

[...truncated 40347 lines...]

Mime
View raw message