Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/883/
Java: 64bit/jdk1.8.0_131 -XX:+UseCompressedOops -XX:+UseSerialGC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.search.LargeFieldTest

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.LargeFieldTest_A3583A5CCB9A201A-001\init-core-data-001:
 java.nio.file.NoSuchFileException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.LargeFieldTest_A3583A5CCB9A201A-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-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.LargeFieldTest_A3583A5CCB9A201A-001\init-core-data-001:
 java.nio.file.NoSuchFileException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.LargeFieldTest_A3583A5CCB9A201A-001\init-core-data-001

        at __randomizedtesting.SeedInfo.seed([A3583A5CCB9A201A]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


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

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard2\collection1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard2\collection1
    
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard2:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard2
    
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-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-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard2\collection1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard2\collection1
   
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard2:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard2
   
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001

        at __randomizedtesting.SeedInfo.seed([A3583A5CCB9A201A]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 11118 lines...]
   [junit4] Suite: org.apache.solr.search.stats.TestExactStatsCache
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\init-core-data-001
   [junit4]   2> 86407 WARN  
(SUITE-TestExactStatsCache-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 86407 INFO  
(SUITE-TestExactStatsCache-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 86412 INFO  
(SUITE-TestExactStatsCache-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 86416 INFO  
(SUITE-TestExactStatsCache-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: 
/fnre/mv
   [junit4]   2> 87333 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\cores\collection1
   [junit4]   2> 87338 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 87339 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6cfda347{/fnre/mv,null,AVAILABLE}
   [junit4]   2> 87341 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@16bb1042{SSL,[ssl, 
http/1.1]}{127.0.0.1:53349}
   [junit4]   2> 87341 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
Started @92584ms
   [junit4]   2> 87341 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fnre/mv, 
hostPort=53349, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\cores}
   [junit4]   2> 87343 ERROR 
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 87343 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 87343 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 87343 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 87343 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T22:38:26.275Z
   [junit4]   2> 87344 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\solr.xml
   [junit4]   2> 87375 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 87564 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\cores
   [junit4]   2> 87565 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 87568 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 87689 WARN  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 87690 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 87728 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 87864 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 87881 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 87881 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 87882 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 87882 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@644cd2ff
   [junit4]   2> 87979 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=2.1328125, 
floorSegmentMB=0.6640625, forceMergeDeletesPctAllowed=3.6383300636432994, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 88179 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 88179 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 88180 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 88180 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 88182 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=92.5654296875, 
floorSegmentMB=1.080078125, forceMergeDeletesPctAllowed=3.5212641924412824, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.721109169787978
   [junit4]   2> 88183 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@504ba1e6[collection1] main]
   [junit4]   2> 88184 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 88194 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 88279 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 88279 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 88280 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 88280 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 88280 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 88281 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 88282 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 88283 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 88283 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 88284 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 88284 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 88292 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@504ba1e6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 88292 INFO  (coreLoadExecutor-190-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1566869403440513024
   [junit4]   2> 89546 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\cores\collection1
   [junit4]   2> 89551 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 89553 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2af2ab6a{/fnre/mv,null,AVAILABLE}
   [junit4]   2> 89555 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@282da39{SSL,[ssl, 
http/1.1]}{127.0.0.1:53361}
   [junit4]   2> 89555 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
Started @94797ms
   [junit4]   2> 89556 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fnre/mv, 
hostPort=53361, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\cores}
   [junit4]   2> 89556 ERROR 
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 89579 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 89579 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 89579 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 89579 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T22:38:28.511Z
   [junit4]   2> 89584 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 89624 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 89745 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\cores
   [junit4]   2> 89745 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 89768 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 89849 WARN  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 89850 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 89892 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 90041 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 90075 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 90076 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 90076 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 90078 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@644cd2ff
   [junit4]   2> 90083 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=2.1328125, 
floorSegmentMB=0.6640625, forceMergeDeletesPctAllowed=3.6383300636432994, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 90330 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 90330 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 90331 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 90331 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 90335 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=92.5654296875, 
floorSegmentMB=1.080078125, forceMergeDeletesPctAllowed=3.5212641924412824, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.721109169787978
   [junit4]   2> 90338 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7c94e357[collection1] main]
   [junit4]   2> 90338 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 90354 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 90483 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 90483 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 90485 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 90485 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 90486 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 90487 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 90488 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 90488 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 90489 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 90496 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 90497 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 90508 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7c94e357[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 90508 INFO  (coreLoadExecutor-201-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1566869405764157440
   [junit4]   2> 90772 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 90911 INFO  (qtp354953736-471) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1566869406182539264)} 0 47
   [junit4]   2> 90962 INFO  (qtp317017461-484) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1566869406231822336)} 0 6
   [junit4]   2> 91012 INFO  (qtp354953736-472) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[0 (1566869406248599552)]} 0 44
   [junit4]   2> 91020 INFO  (qtp317017461-489) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[0 (1566869406297882624)]} 0 3
   [junit4]   2> 91030 INFO  (qtp354953736-474) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 91030 INFO  (qtp354953736-474) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6dd12bac
   [junit4]   2> 91194 INFO  (qtp354953736-474) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4fff563e[collection1] main]
   [junit4]   2> 91196 INFO  (qtp354953736-474) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 91206 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4fff563e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))}
   [junit4]   2> 91207 INFO  (qtp354953736-474) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 177
   [junit4]   2> 91211 INFO  (qtp317017461-495) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 91211 INFO  (qtp317017461-495) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5572f04a
   [junit4]   2> 91237 INFO  (qtp317017461-495) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1131983c[collection1] main]
   [junit4]   2> 91239 INFO  (qtp317017461-495) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 91242 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1131983c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))}
   [junit4]   2> 91243 INFO  (qtp317017461-495) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 31
   [junit4]   2> 91257 INFO  (qtp354953736-468) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv path=/select 
params={q=a_t:one&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=1 
status=0 QTime=11
   [junit4]   2> 91304 INFO  (qtp317017461-496) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv path=/select 
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=https://127.0.0.1:53361/fnre/mv/collection1&rid=127.0.0.1-collection1-1494283110207-0&version=2&q=a_t:one&requestPurpose=GET_TERM_STATS&NOW=1494283110206&isShard=true&wt=javabin&debugQuery=false}
 status=0 QTime=13
   [junit4]   2> 91323 INFO  (qtp317017461-496) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv 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=https://127.0.0.1:53361/fnre/mv/collection1&rid=127.0.0.1-collection1-1494283110207-0&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=1494283110206&isShard=true&org.apache.solr.stats.terms=a_t:on&wt=javabin&debugQuery=false}
 hits=1 status=0 QTime=11
   [junit4]   2> 91367 INFO  (qtp317017461-496) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv path=/select 
params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=https://127.0.0.1:53361/fnre/mv/collection1&rid=127.0.0.1-collection1-1494283110207-0&version=2&q=a_t:one&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1494283110206&ids=0&isShard=true&wt=javabin&debugQuery=true}
 status=0 QTime=22
   [junit4]   2> 91371 INFO  (qtp317017461-494) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv path=/select 
params={q=a_t:one&shards=https://127.0.0.1:53361/fnre/mv/collection1&fl=*,score&wt=javabin&version=2&debugQuery=true}
 rid=127.0.0.1-collection1-1494283110207-0 hits=1 status=0 QTime=96
   [junit4]   1> ======================= Control Response 
=======================
   [junit4]   1> 
{responseHeader={status=0,QTime=11},response={numFound=1,start=0,maxScore=0.5,docs=[SolrDocument{id=0,
 range_facet_l=[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 
May 08 22:38:29 UTC 2017, _version_=1566869406248599552, intDvoDefault=42, 
score=0.5}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0=
   [junit4]   1> 0.5 = weight(a_t:on in 0) [MockConfigurableSimilarity], result 
of:
   [junit4]   1>   0.5 = 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.5 = fieldNorm(doc=0)
   [junit4]   1> 
},QParser=LuceneQParser,timing={time=11.0,prepare={time=1.0,query={time=1.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=5.0,query={time=4.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=1.0}}}}}
   [junit4]   1> 
   [junit4]   1> 
   [junit4]   1> ======================= Shard Response =======================
   [junit4]   1> 
   [junit4]   1> 
{responseHeader={status=0,QTime=96},response={numFound=1,start=0,maxScore=0.5,docs=[SolrDocument{id=0,
 range_facet_l=[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 
May 08 22:38:29 UTC 2017, _version_=1566869406297882624, intDvoDefault=42, 
score=0.5}]},debug={track={rid=127.0.0.1-collection1-1494283110207-0,PARSE_QUERY={https://127.0.0.1:53361/fnre/mv/collection1={QTime=13,ElapsedTime=28,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=13},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=13.0,prepare={time=3.0,query={time=2.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=9.0,query={time=9.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={https://127.0.0.1:53361/fnre/mv/collection1={QTime=11,ElapsedTime=24,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=1,Response={responseHeader={status=0,QTime=11},response={numFound=1,start=0,maxScore=0.5,docs=[SolrDocument{id=0,
 
score=0.5}]},sort_values={},debug={timing={time=11.0,prepare={time=3.0,query={time=3.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=6.0,query={time=6.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={https://127.0.0.1:53361/fnre/mv/collection1={QTime=22,ElapsedTime=28,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=1,Response={responseHeader={status=0,QTime=22},response={numFound=1,start=0,docs=[SolrDocument{id=0,
 range_facet_l=[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 
May 08 22:38:29 UTC 2017, _version_=1566869406297882624, intDvoDefault=42, 
score=0.5}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0=
   [junit4]   1> 0.5 = weight(a_t:on in 0) [MockConfigurableSimilarity], result 
of:
   [junit4]   1>   0.5 = 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.5 = fieldNorm(doc=0)
   [junit4]   1> 
},QParser=LuceneQParser,timing={time=22.0,prepare={time=1.0,query={time=1.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=20.0,query={time=15.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=5.0}}}}}}}},timing={time=46.0,prepare={time=7.0,query={time=6.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=35.0,query={time=30.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=5.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.5 = weight(a_t:on in 0) [MockConfigurableSimilarity], result 
of:
   [junit4]   1>   0.5 = 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.5 = fieldNorm(doc=0)
   [junit4]   1> }}}
   [junit4]   2> 91383 INFO  (qtp354953736-473) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[1 (1566869406677467136)]} 0 4
   [junit4]   2> 91390 INFO  (qtp317017461-485) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[1 (1566869406684807168)]} 0 3
   [junit4]   2> 91394 INFO  (qtp354953736-469) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 91395 INFO  (qtp354953736-469) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6dd12bac
   [junit4]   2> 91440 INFO  (qtp354953736-469) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4083e408[collection1] main]
   [junit4]   2> 91446 INFO  (qtp354953736-469) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 91447 INFO  
(searcherExecutor-191-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4083e408[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)
 Uninverting(_1(6.6.0):C1)))}
   [junit4]   2> 91448 INFO  (qtp354953736-469) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 54
   [junit4]   2> 91451 INFO  (qtp317017461-484) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 91452 INFO  (qtp317017461-484) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5572f04a
   [junit4]   2> 91481 INFO  (qtp317017461-484) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@e13e96[collection1] main]
   [junit4]   2> 91484 INFO  (qtp317017461-484) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 91487 INFO  
(searcherExecutor-202-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e13e96[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)
 Uninverting(_1(6.6.0):C1)))}
   [junit4]   2> 91488 INFO  (qtp317017461-484) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 36
   [junit4]   2> 91533 INFO  (qtp354953736-470) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv path=/select 
params={q=a_t:one+a_t:four&fl=*,score&wt=javabin&version=2&debugQuery=true} 
hits=2 status=0 QTime=42
   [junit4]   2> 91545 INFO  (qtp317017461-495) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv path=/select 
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=https://127.0.0.1:53361/fnre/mv/collection1&rid=127.0.0.1-collection1-1494283110473-1&version=2&q=a_t:one+a_t:four&requestPurpose=GET_TERM_STATS&NOW=1494283110472&isShard=true&wt=javabin&debugQuery=false}
 status=0 QTime=1
   [junit4]   2> 91553 INFO  (qtp317017461-491) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv 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=https://127.0.0.1:53361/fnre/mv/collection1&rid=127.0.0.1-collection1-1494283110473-1&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=1494283110472&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=2
   [junit4]   2> 91563 INFO  (qtp317017461-486) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv path=/select 
params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=https://127.0.0.1:53361/fnre/mv/collection1&rid=127.0.0.1-collection1-1494283110473-1&version=2&q=a_t:one+a_t:four&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1494283110472&ids=0,1&isShard=true&wt=javabin&debugQuery=true}
 status=0 QTime=4
   [junit4]   2> 91576 INFO  (qtp317017461-489) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/fnre/mv path=/select 
params={q=a_t:one+a_t:four&shards=https://127.0.0.1:53361/fnre/mv/collection1&fl=*,score&wt=javabin&version=2&debugQuery=true}
 rid=127.0.0.1-collection1-1494283110473-1 hits=2 status=0 QTime=35
   [junit4]   1> ======================= Control Response 
=======================
   [junit4]   1> 
{responseHeader={status=0,QTime=42},response={numFound=2,start=0,maxScore=0.75465035,docs=[SolrDocument{id=1,
 range_facet_l=[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 May 08 22:38:30 UTC 2017, _version_=1566869406677467136, 
intDvoDefault=42, score=0.75465035}, SolrDocument{id=0, range_facet_l=[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 May 08 22:38:29 UTC 
2017, _version_=1566869406248599552, intDvoDefault=42, 
score=0.14493467}]},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> 0.75465035 = sum of:
   [junit4]   1>   0.25363567 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.25363567 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.5797387 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>       0.4375 = 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.4375 = fieldNorm(doc=0)
   [junit4]   1>   0.5010147 = weight(a_t:four in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.5010147 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.81480247 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>       0.614891 = 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.4375 = fieldNorm(doc=0)
   [junit4]   1> ,0=
   [junit4]   1> 0.14493467 = product of:
   [junit4]   1>   0.28986934 = sum of:
   [junit4]   1>     0.28986934 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>       0.28986934 = score(doc=0,freq=1.0), product of:
   [junit4]   1>         0.5797387 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>         0.5 = 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.5 = fieldNorm(doc=0)
   [junit4]   1>   0.5 = coord(1/2)
   [junit4]   1> 
},QParser=LuceneQParser,timing={time=42.0,prepare={time=1.0,query={time=1.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=40.0,query={time=34.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=5.0}}}}}
   [junit4]   1> 
   [junit4]   1> 
   [junit4]   1> ======================= Shard Response =======================
   [junit4]   1> 
   [junit4]   1> 
{responseHeader={status=0,QTime=35},response={numFound=2,start=0,maxScore=0.75465035,docs=[SolrDocument{id=1,
 range_facet_l=[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 May 08 22:38:30 UTC 2017, _version_=1566869406684807168, 
intDvoDefault=42, score=0.75465035}, SolrDocument{id=0, range_facet_l=[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 May 08 22:38:29 UTC 
2017, _version_=1566869406297882624, intDvoDefault=42, 
score=0.14493467}]},debug={track={rid=127.0.0.1-collection1-1494283110473-1,PARSE_QUERY={https://127.0.0.1:53361/fnre/mv/collection1={QTime=1,ElapsedTime=6,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=1},org.apache.solr.stats.terms=a_t:four,org.apache.solr.stats.terms=a_t:on,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={https://127.0.0.1:53361/fnre/mv/collection1={QTime=2,ElapsedTime=7,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=2,Response={responseHeader={status=0,QTime=2},response={numFound=2,start=0,maxScore=0.75465035,docs=[SolrDocument{id=1,
 score=0.75465035}, SolrDocument{id=0, 
score=0.14493467}]},sort_values={},debug={timing={time=2.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=1.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={https://127.0.0.1:53361/fnre/mv/collection1={QTime=4,ElapsedTime=13,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=2,Response={responseHeader={status=0,QTime=4},response={numFound=2,start=0,docs=[SolrDocument{id=0,
 range_facet_l=[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 
May 08 22:38:29 UTC 2017, _version_=1566869406297882624, intDvoDefault=42, 
score=0.14493467}, SolrDocument{id=1, range_facet_l=[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 May 08 22:38:30 UTC 
2017, _version_=1566869406684807168, intDvoDefault=42, 
score=0.75465035}]},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.14493467 = product of:
   [junit4]   1>   0.28986934 = sum of:
   [junit4]   1>     0.28986934 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>       0.28986934 = score(doc=0,freq=1.0), product of:
   [junit4]   1>         0.5797387 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>         0.5 = 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.5 = fieldNorm(doc=0)
   [junit4]   1>   0.5 = coord(1/2)
   [junit4]   1> ,1=
   [junit4]   1> 0.75465035 = sum of:
   [junit4]   1>   0.25363567 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.25363567 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.5797387 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>       0.4375 = 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.4375 = fieldNorm(doc=0)
   [junit4]   1>   0.5010147 = weight(a_t:four in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.5010147 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.81480247 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>       0.614891 = 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.4375 = fieldNorm(doc=0)
   [junit4]   1> 
},QParser=LuceneQParser,timing={time=4.0,prepare={time=2.0,query={time=2.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}}}}}}}},timing={time=6.0,prepare={time=2.0,query={time=2.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=2.0,query={time=1.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> 0.75465035 = sum of:
   [junit4]   1>   0.25363567 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.25363567 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.5797387 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>       0.4375 = 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.4375 = fieldNorm(doc=0)
   [junit4]   1>   0.5010147 = weight(a_t:four in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.5010147 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.81480247 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>       0.614891 = 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.4375 = fieldNorm(doc=0)
   [junit4]   1> ,0=
   [junit4]   1> 0.14493467 = product of:
   [junit4]   1>   0.28986934 = sum of:
   [junit4]   1>     0.28986934 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>       0.28986934 = score(doc=0,freq=1.0), product of:
   [junit4]   1>         0.5797387 = queryWeight, product of:
   [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.5797387 = queryNorm
   [junit4]   1>         0.5 = 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.5 = fieldNorm(doc=0)
   [junit4]   1>   0.5 = coord(1/2)
   [junit4]   1> }}}
   [junit4]   2> 91582 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 91584 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@16bb1042{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 91585 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=984575399
   [junit4]   2> 91585 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 91585 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 91585 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 91596 INFO  (coreCloseExecutor-207-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7086447
   [junit4]   2> 91928 INFO  (coreCloseExecutor-207-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 91929 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6cfda347{/fnre/mv,null,UNAVAILABLE}
   [junit4]   2> 91934 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@282da39{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 91934 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=19646730
   [junit4]   2> 91934 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 91934 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 91934 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 91936 INFO  (coreCloseExecutor-209-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@d84aa3f
   [junit4]   2> 92077 INFO  (coreCloseExecutor-209-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 92078 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2af2ab6a{/fnre/mv,null,UNAVAILABLE}
   [junit4]   2> 92474 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 92477 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5a697d10{/fnre/mv,null,AVAILABLE}
   [junit4]   2> 92478 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@3303a237{SSL,[ssl, 
http/1.1]}{127.0.0.1:53395}
   [junit4]   2> 92481 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
Started @97722ms
   [junit4]   2> 92481 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fnre/mv, 
hostPort=53395, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\cores}
   [junit4]   2> 92481 ERROR 
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 92481 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 92481 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 92481 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 92481 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T22:38:31.413Z
   [junit4]   2> 92482 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\solr.xml
   [junit4]   2> 92498 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 92601 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\cores
   [junit4]   2> 92602 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 92608 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 92697 WARN  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 92698 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 92726 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 92859 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 92877 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 92877 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 92878 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 92878 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@644cd2ff
   [junit4]   2> 92880 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=2.1328125, 
floorSegmentMB=0.6640625, forceMergeDeletesPctAllowed=3.6383300636432994, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 93050 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 93050 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 93050 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 93050 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 93051 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=92.5654296875, 
floorSegmentMB=1.080078125, forceMergeDeletesPctAllowed=3.5212641924412824, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.721109169787978
   [junit4]   2> 93055 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@79bb8b61[collection1] main]
   [junit4]   2> 93055 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 93058 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 93072 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 93072 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 93073 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 93073 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 93073 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 93074 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 93075 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 93075 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 93075 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 93076 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 93077 INFO  (coreLoadExecutor-216-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1566869408457949184
   [junit4]   2> 93077 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 93079 INFO  
(searcherExecutor-217-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@79bb8b61[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 93591 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 93593 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3698aee5{/fnre/mv,null,AVAILABLE}
   [junit4]   2> 93595 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@7a38a12e{SSL,[ssl, 
http/1.1]}{127.0.0.1:53398}
   [junit4]   2> 93595 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
Started @98837ms
   [junit4]   2> 93595 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fnre/mv, 
hostPort=53398, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\cores}
   [junit4]   2> 93595 ERROR 
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 93597 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 93597 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 93597 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 93613 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T22:38:32.545Z
   [junit4]   2> 93614 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 93628 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 93728 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\cores
   [junit4]   2> 93729 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 93736 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 93828 WARN  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 93830 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 93854 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 93973 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 93999 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 93999 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 93999 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 94000 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@644cd2ff
   [junit4]   2> 94001 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=2.1328125, 
floorSegmentMB=0.6640625, forceMergeDeletesPctAllowed=3.6383300636432994, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 94198 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 94199 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 94199 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 94199 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 94202 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=92.5654296875, 
floorSegmentMB=1.080078125, forceMergeDeletesPctAllowed=3.5212641924412824, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.721109169787978
   [junit4]   2> 94203 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@530fd9ed[collection1] main]
   [junit4]   2> 94203 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 94207 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 94221 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 94222 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 94222 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 94223 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 94223 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 94223 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 94224 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 94224 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 94225 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 94225 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 94226 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 94229 INFO  
(searcherExecutor-228-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@530fd9ed[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 94229 INFO  (coreLoadExecutor-227-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1566869409665908736
   [junit4]   2> 94977 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard1\cores\collection1
   [junit4]   2> 94982 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 94984 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2cd24300{/fnre/mv,null,AVAILABLE}
   [junit4]   2> 94985 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@51933188{SSL,[ssl, 
http/1.1]}{127.0.0.1:53401}
   [junit4]   2> 94985 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] o.e.j.s.Server 
Started @100227ms
   [junit4]   2> 94985 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/fnre/mv, 
hostPort=53401, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard1\cores}
   [junit4]   2> 94986 ERROR 
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 94986 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 94986 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 94986 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 94986 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-08T22:38:33.918Z
   [junit4]   2> 94987 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard1\solr.xml
   [junit4]   2> 95002 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 95096 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard1\cores
   [junit4]   2> 95096 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 95098 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 95180 WARN  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 95180 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 95201 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 95320 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 95334 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 95334 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 95334 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.stats.TestExactStatsCache_A3583A5CCB9A201A-001\tempDir-001\shard1\cores\collection1\data\]
   [junit4]   2> 95336 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@644cd2ff
   [junit4]   2> 95338 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=2.1328125, 
floorSegmentMB=0.6640625, forceMergeDeletesPctAllowed=3.6383300636432994, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 95493 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 95493 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 95495 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 95495 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 95496 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=92.5654296875, 
floorSegmentMB=1.080078125, forceMergeDeletesPctAllowed=3.5212641924412824, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.721109169787978
   [junit4]   2> 95496 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3f84fe79[collection1] main]
   [junit4]   2> 95497 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 95509 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 95595 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 95596 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 95596 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 95596 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 95596 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 95596 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 95598 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 95598 INFO  (coreLoadExecutor-238-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1566869411101409280
   [junit4]   2> 95599 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 95599 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 95601 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 95601 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 95609 INFO  
(searcherExecutor-239-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3f84fe79[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 95617 INFO  
(TEST-TestExactStatsCache.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 95654 INFO  (qtp1940326423-517) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1566869411155935232)} 0 4
   [junit4]   2> 95692 INFO  (qtp84991067-530) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1566869411195781120)} 0 4
   [junit4]   2> 95732 INFO  (qtp676523703-554) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1566869411233529856)} 0 7
   [junit4]   2> 95738 INFO  (qtp1940326423-516) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[2 (1566869411246112768)]} 0 3
   [junit4]   2> 95746 INFO  (qtp84991067-534) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[2 (1566869411252404224)]} 0 2
   [junit4]   2> 95751 INFO  (qtp1940326423-515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[3 (1566869411259744256)]} 0 1
   [junit4]   2> 95759 INFO  (qtp676523703-555) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[3 (1566869411264987136)]} 0 3
   [junit4]   2> 95763 INFO  (qtp1940326423-512) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[4 (1566869411273375744)]} 0 1
   [junit4]   2> 95766 INFO  (qtp676523703-556) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/fnre/mv path=/update 
params={wt=javabin&version=2}{add=[4 (1566869411275472896)]} 0 2
   [junit4]   2> 95769 INFO  (qtp1940326423-514) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95769 INFO  (qtp1940326423-514) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@45f277b
   [junit4]   2> 

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

it4]   2> 360536 INFO  (coreLoadExecutor-1372-thread-1) [    x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 360538 INFO  (coreLoadExecutor-1372-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@644cd2ff
   [junit4]   2> 360538 INFO  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 360538 INFO  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.LargeFieldTest_A3583A5CCB9A201A-001\init-core-data-001\]
   [junit4]   2> 360543 INFO  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=30, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.357377333517083]
   [junit4]   2> 360552 WARN  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 360650 INFO  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 360650 INFO  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 360652 INFO  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 360653 INFO  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@134544e8[collection1] main]
   [junit4]   2> 360653 WARN  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 360654 INFO  (coreLoadExecutor-1372-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 360656 INFO  
(SUITE-LargeFieldTest-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 360669 INFO  
(searcherExecutor-1373-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@134544e8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 360673 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] o.a.s.SolrTestCaseJ4 
###Starting test
   [junit4]   2> 360675 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[101]} 0 2
   [junit4]   2> 360680 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 360680 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c2fc255
   [junit4]   2> 360765 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@381de3c2[collection1] main]
   [junit4]   2> 360766 INFO  (searcherExecutor-1373-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@381de3c2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))}
   [junit4]   2> 360766 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 360766 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 85
   [junit4]   2> 360777 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=101&df=str&fl=str&wt=xml} hits=1 
status=0 QTime=10
   [junit4]   2> 360778 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=101&df=str&fl=lazyField&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 360778 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=101&df=str&fl=bigField&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 360779 INFO  
(TEST-LargeFieldTest.test-seed#[A3583A5CCB9A201A]) [    ] o.a.s.SolrTestCaseJ4 
###Ending test
   [junit4]   2> 360779 INFO  
(SUITE-LargeFieldTest-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 360780 INFO  
(SUITE-LargeFieldTest-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1652139586
   [junit4]   2> 360780 INFO  
(SUITE-LargeFieldTest-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 360781 INFO  
(SUITE-LargeFieldTest-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 360782 INFO  
(SUITE-LargeFieldTest-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 360782 INFO  
(SUITE-LargeFieldTest-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 360783 INFO  (coreCloseExecutor-1377-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@48d9fa7b
   [junit4]   2> 360786 INFO  (coreCloseExecutor-1377-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 360828 INFO  
(SUITE-LargeFieldTest-seed#[A3583A5CCB9A201A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=346, maxMBSortInHeap=6.0899319668778045, 
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=zh-SG, timezone=BST
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=134661208,total=290107392
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestInPlaceUpdatesStandalone, TestMaxScoreQueryParser, DeleteStatusTest, 
HdfsChaosMonkeyNothingIsSafeTest, RulesTest, V2ApiIntegrationTest, 
TestExactStatsCache, TestExtendedDismaxParser, HdfsRecoverLeaseTest, 
TestLockTree, HdfsCollectionsAPIDistributedZkTest, SolrCoreMetricManagerTest, 
ZkNodePropsTest, TestConfigSetImmutable, SimplePostToolTest, 
TestHighFrequencyDictionaryFactory, TestElisionMultitermQuery, 
TestReRankQParserPlugin, OverseerStatusTest, TestSolrJ, 
TestRandomRequestDistribution, TestStressReorder, TestJsonRequest, 
BlockDirectoryTest, TestFieldTypeCollectionResource, 
TestHighlightDedupGrouping, TestDFISimilarityFactory, TestScoreJoinQPNoScore, 
SOLR749Test, TestCloudPivotFacet, TestUpdate, InfoHandlerTest, 
TestBinaryResponseWriter, TestCustomStream, TestManagedSchemaAPI, 
RollingRestartTest, BigEndianAscendingWordDeserializerTest, 
TestManagedStopFilterFactory, TestCursorMarkWithoutUniqueKey, 
CurrencyFieldXmlFileTest, TestStressRecovery, 
TestLeaderElectionWithEmptyReplica, TestSerializedLuceneMatchVersion, 
TestFastOutputStream, TestJettySolrRunner, TestSolrConfigHandlerConcurrent, 
TestStressVersions, TestRTimerTree, SharedFSAutoReplicaFailoverTest, 
OutputWriterTest, BlockJoinFacetSimpleTest, TestAddFieldRealTimeGet, 
LargeFieldTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=LargeFieldTest 
-Dtests.seed=A3583A5CCB9A201A -Dtests.slow=true -Dtests.locale=zh-SG 
-Dtests.timezone=BST -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | LargeFieldTest (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-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.LargeFieldTest_A3583A5CCB9A201A-001\init-core-data-001:
 java.nio.file.NoSuchFileException: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.LargeFieldTest_A3583A5CCB9A201A-001\init-core-data-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A3583A5CCB9A201A]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [98/711 (2!)] on J0 in 0.63s, 1 test, 1 error <<< 
FAILURES!

[...truncated 45449 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to