[JENKINS] Lucene-Solr-7.x-Windows (32bit/jdk1.8.0_144) - Build # 489 - Still Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/489/
Java: 32bit/jdk1.8.0_144 -server -XX:+UseParallelGC

4 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.lucene.spatial.prefix.DateNRStrategyTest

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001\segments_1:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001\segments_1
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001\segments_1:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001\segments_1
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001

        at __randomizedtesting.SeedInfo.seed([B4DBD589001F91F0]: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.handler.component.DistributedTermsComponentTest

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1\collection1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1\collection1
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1\collection1\conf:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1\collection1\conf
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1\collection1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1\collection1
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1\collection1\conf:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001\tempDir-001\shard1\collection1\conf
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.component.DistributedTermsComponentTest_E3CB946A23D22B1-001

        at __randomizedtesting.SeedInfo.seed([E3CB946A23D22B1]: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.handler.component.TermVectorComponentDistributedTest

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1\configsets\cloud-managed:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1\configsets\cloud-managed
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1\configsets:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1\configsets
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1\configsets\cloud-managed:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1\configsets\cloud-managed
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1\configsets:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1\configsets
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard1
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001

        at __randomizedtesting.SeedInfo.seed([E3CB946A23D22B1]: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.response.transform.TestChildDocTransformer

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog\tlog.0000000000000000001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog\tlog.0000000000000000001
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog\tlog.0000000000000000001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog\tlog.0000000000000000001
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001

        at __randomizedtesting.SeedInfo.seed([E3CB946A23D22B1]: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 9808 lines...]
   [junit4] Suite: org.apache.lucene.spatial.prefix.DateNRStrategyTest
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{dateRange=PostingsFormat(name=Memory), id=PostingsFormat(name=Memory)}, 
docValues:{}, maxPointsInLeafNode=1269, maxMBSortInHeap=7.585209856805035, 
sim=RandomSimilarity(queryNorm=true): {}, locale=ja-JP, 
timezone=America/Miquelon
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=100392776,total=217055232
   [junit4]   2> NOTE: All tests run in this JVM: 
[Geo3dShapeSphereModelRectRelationTest, TestTestFramework, SpatialArgsTest, 
QueryEqualsHashCodeTest, TestRecursivePrefixTreeStrategy, 
RandomSpatialOpFuzzyPrefixTree50Test, PortedSolr3Test, DistanceStrategyTest, 
SpatialPrefixTreeTest, TestTermQueryPrefixGridStrategy, JtsPolygonTest, 
CompositeStrategyTest, DateNRStrategyTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=DateNRStrategyTest 
-Dtests.seed=B4DBD589001F91F0 -Dtests.slow=true -Dtests.locale=ja-JP 
-Dtests.timezone=America/Miquelon -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J0 | DateNRStrategyTest (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001\segments_1:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001\index-SimpleFSDirectory-001\segments_1
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\spatial-extras\test\J0\temp\lucene.spatial.prefix.DateNRStrategyTest_B4DBD589001F91F0-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([B4DBD589001F91F0]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [20/24 (1!)] on J0 in 1.71s, 34 tests, 1 error <<< 
FAILURES!

[...truncated 2411 lines...]
   [junit4] Suite: 
org.apache.solr.handler.component.TermVectorComponentDistributedTest
   [junit4]   2> 126126 INFO  
(SUITE-TermVectorComponentDistributedTest-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\init-core-data-001
   [junit4]   2> 126131 WARN  
(SUITE-TermVectorComponentDistributedTest-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=27 numCloses=27
   [junit4]   2> 126131 INFO  
(SUITE-TermVectorComponentDistributedTest-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 126137 INFO  
(SUITE-TermVectorComponentDistributedTest-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 126137 INFO  
(SUITE-TermVectorComponentDistributedTest-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 127291 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\control\cores\collection1
   [junit4]   2> 127305 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 127306 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 127306 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 127306 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 127307 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@600b47{/,null,AVAILABLE}
   [junit4]   2> 127311 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1a81c18{HTTP/1.1,[http/1.1]}{127.0.0.1:59038}
   [junit4]   2> 127311 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.Server Started @136318ms
   [junit4]   2> 127312 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=59038, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\control\cores}
   [junit4]   2> 127312 ERROR 
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 127312 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 127312 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 127312 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 127312 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-07T19:31:51.335Z
   [junit4]   2> 127313 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\control\solr.xml
   [junit4]   2> 127324 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 127324 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 127325 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@142893, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 127449 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142893
   [junit4]   2> 127456 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142893
   [junit4]   2> 127457 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142893
   [junit4]   2> 127461 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\control\cores
   [junit4]   2> 127461 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 127464 INFO  (coreLoadExecutor-501-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 127466 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 127556 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 127579 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 127685 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 127707 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 127708 INFO  (coreLoadExecutor-501-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@142893
   [junit4]   2> 127708 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 127708 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 127712 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=10, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=37.1025390625, 
floorSegmentMB=1.6064453125, forceMergeDeletesPctAllowed=7.247582765661992, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49367101856260376
   [junit4]   2> 127962 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 127963 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 127964 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 127965 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 127965 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.48851305370358167]
   [junit4]   2> 127966 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ddbb77[collection1] 
main]
   [junit4]   2> 127967 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 128025 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 128355 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 128355 INFO  (coreLoadExecutor-501-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1594308545612873728
   [junit4]   2> 128356 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 128356 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 128356 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 128356 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 128357 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 128357 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 128357 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 128357 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 128359 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 128360 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ddbb77[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 129123 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard0\cores\collection1
   [junit4]   2> 129128 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 129128 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 129129 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 129129 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 129129 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1f4ce00{/,null,AVAILABLE}
   [junit4]   2> 129130 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4a012f{HTTP/1.1,[http/1.1]}{127.0.0.1:59044}
   [junit4]   2> 129130 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.e.j.s.Server Started @138137ms
   [junit4]   2> 129130 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=59044, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard0\cores}
   [junit4]   2> 129131 ERROR 
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 129131 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 129131 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 129131 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 129131 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-07T19:31:53.154Z
   [junit4]   2> 129132 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 129142 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 129142 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 129142 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@142893, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 129261 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142893
   [junit4]   2> 129280 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142893
   [junit4]   2> 129280 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142893
   [junit4]   2> 129286 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard0\cores
   [junit4]   2> 129286 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 129287 INFO  (coreLoadExecutor-512-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 129288 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 129396 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 129435 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 129520 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 129534 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 129535 INFO  (coreLoadExecutor-512-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@142893
   [junit4]   2> 129535 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 129535 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.component.TermVectorComponentDistributedTest_E3CB946A23D22B1-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 129538 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=10, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=37.1025390625, 
floorSegmentMB=1.6064453125, forceMergeDeletesPctAllowed=7.247582765661992, 
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.49367101856260376
   [junit4]   2> 129674 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 129674 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 129675 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 129675 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 129676 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.48851305370358167]
   [junit4]   2> 129676 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13eb2b4[collection1] 
main]
   [junit4]   2> 129677 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 129695 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 129815 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 129817 INFO  (coreLoadExecutor-512-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1594308547145891840
   [junit4]   2> 129818 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 129819 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 129819 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 129819 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 129819 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 129820 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 129820 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 129820 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 129821 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 129822 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13eb2b4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 129823 INFO  
(TEST-TermVectorComponentDistributedTest.test-seed#[E3CB946A23D22B1]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 129857 INFO  (qtp21445109-3281) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[0 (1594308547157426176)]} 0 29
   [junit4]   2> 129865 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[0 (1594308547189932032)]} 0 4
   [junit4]   2> 129879 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1594308547199369216)]} 0 11
   [junit4]   2> 129881 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1594308547211952128)]} 0 1
   [junit4]   2> 129882 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1594308547214049280)]} 0 0
   [junit4]   2> 129883 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1594308547215097856)]} 0 0
   [junit4]   2> 129883 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1594308547215097856)]} 0 0
   [junit4]   2> 129888 INFO  (qtp13112278-3304) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1594308547215097857)]} 0 2
   [junit4]   2> 129890 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1594308547221389312)]} 0 0
   [junit4]   2> 129891 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1594308547223486464)]} 0 0
   [junit4]   2> 129892 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1594308547224535040)]} 0 0
   [junit4]   2> 129894 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1594308547226632192)]} 0 0
   [junit4]   2> 129895 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1594308547227680768)]} 0 0
   [junit4]   2> 129897 INFO  (qtp13112278-3304) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1594308547228729344)]} 0 0
   [junit4]   2> 129898 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1594308547230826496)]} 0 0
   [junit4]   2> 129900 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1594308547231875072)]} 0 0
   [junit4]   2> 129902 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1594308547232923648)]} 0 0
   [junit4]   2> 129903 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1594308547235020800)]} 0 0
   [junit4]   2> 129905 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1594308547237117952)]} 0 0
   [junit4]   2> 129907 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1594308547239215104)]} 0 1
   [junit4]   2> 129908 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594308547241312256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 129908 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@639d36 
commitCommandVersion:1594308547241312256
   [junit4]   2> 130000 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@15f7931[collection1] main]
   [junit4]   2> 130000 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 130002 INFO  
(searcherExecutor-502-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15f7931[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C10)))}
   [junit4]   2> 130002 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 94
   [junit4]   2> 130003 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594308547340926976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 130003 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1e68dd3 
commitCommandVersion:1594308547340926976
   [junit4]   2> 130028 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1fc0c05[collection1] main]
   [junit4]   2> 130029 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 130030 INFO  
(searcherExecutor-513-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1fc0c05[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C10)))}
   [junit4]   2> 130030 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 27
   [junit4]   2> 130034 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&distrib=false&qt=tvrh&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=2
   [junit4]   2> 130048 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&shards.purpose=4&tv.tf=true&start=0&fsv=true&sort=id+desc&shard.url=http://127.0.0.1:59044//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=id:0&NOW=1520451114058&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130060 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&shards.purpose=64&tv.tf=true&shard.url=http://127.0.0.1:59044//collection1|[ff01::114]:33332/|[ff01::213]:33332/&version=2&q=id:0&NOW=1520451114058&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130063 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=http://127.0.0.1:59044//collection1|[ff01::114]:33332/|[ff01::213]:33332/&tv=true&qt=tvrh&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=26
   [junit4]   2> 130067 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&distrib=false&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv,test_offtv&wt=javabin&version=2}
 hits=1 status=0 QTime=1
   [junit4]   2> 130078 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=32768&tv.tf=true&sort=id+asc&tv.fl=test_basictv,test_offtv&shard.url=http://127.0.0.1:59044//collection1&version=2&q=id:0&NOW=1520451114091&isShard=true&wt=javabin}
 status=0 QTime=8
   [junit4]   2> 130080 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:0,1,1&sort=id+asc&tv.fl=test_basictv,test_offtv&shard.url=http://127.0.0.1:59044//collection1&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:0&NOW=1520451114091&isShard=true&org.apache.solr.stats.terms=id:0&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130081 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=64&tv.tf=true&tv.fl=test_basictv,test_offtv&shard.url=http://127.0.0.1:59044//collection1&version=2&q=id:0&NOW=1520451114091&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130082 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=http://127.0.0.1:59044//collection1&tv=true&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv,test_offtv&wt=javabin&version=2}
 hits=1 status=0 QTime=13
   [junit4]   2> 130083 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&distrib=false&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv&tv.fl=test_offtv&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130089 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=32768&tv.tf=true&sort=id+asc&tv.fl=test_basictv&tv.fl=test_offtv&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&version=2&q=id:0&NOW=1520451114107&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130090 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:0,1,1&sort=id+asc&tv.fl=test_basictv&tv.fl=test_offtv&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:0&NOW=1520451114107&isShard=true&org.apache.solr.stats.terms=id:0&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130091 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=64&tv.tf=true&tv.fl=test_basictv&tv.fl=test_offtv&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&version=2&q=id:0&NOW=1520451114107&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130092 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&tv=true&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv&tv.fl=test_offtv&wt=javabin&version=2}
 hits=1 status=0 QTime=7
   [junit4]   2> 130093 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&distrib=false&qt=tvrh&fl=*,score&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130095 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=32768&tv.tf=true&sort=id+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&version=2&q=id:0&NOW=1520451114117&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130096 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:0,1,1&sort=id+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:0&NOW=1520451114117&isShard=true&org.apache.solr.stats.terms=id:0&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130098 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=64&tv.tf=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&version=2&q=id:0&NOW=1520451114117&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130098 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&tv=true&qt=tvrh&fl=*,score&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=4
   [junit4]   2> 130100 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&distrib=false&qt=tvrh&fl=score,test_basictv,[docid],test_postv,val:sum(3,4)&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130113 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=score,test_basictv,[docid],test_postv,val:sum(3,4)&shards.purpose=32768&tv.tf=true&sort=id+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:59044//collection1&version=2&q=id:0&NOW=1520451114123&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130114 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:0,1,1&sort=id+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:59044//collection1&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:0&NOW=1520451114123&isShard=true&org.apache.solr.stats.terms=id:0&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130115 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=score,test_basictv,[docid],test_postv,val:sum(3,4)&fl=id&shards.purpose=64&tv.tf=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:59044//collection1&version=2&q=id:0&NOW=1520451114123&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130116 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:59044//collection1&tv=true&qt=tvrh&fl=score,test_basictv,[docid],test_postv,val:sum(3,4)&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=15
   [junit4]   2> 130117 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&distrib=false&qt=tvrh&fl=score,test_basictv&fl=[docid],test_postv,val:sum(3,4)&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130119 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=score,test_basictv&fl=[docid],test_postv,val:sum(3,4)&shards.purpose=32768&tv.tf=true&sort=id+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::114]:33332/&version=2&q=id:0&NOW=1520451114140&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130120 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:0,1,1&sort=id+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::114]:33332/&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:0&NOW=1520451114140&isShard=true&org.apache.solr.stats.terms=id:0&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130121 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=score,test_basictv&fl=[docid],test_postv,val:sum(3,4)&fl=id&shards.purpose=64&tv.tf=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::114]:33332/&version=2&q=id:0&NOW=1520451114140&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130121 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::114]:33332/&tv=true&qt=tvrh&fl=score,test_basictv&fl=[docid],test_postv,val:sum(3,4)&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 130123 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&tv.positions=true&distrib=false&qt=tvrh&tv.tf=true&tv.df=true&tv.offsets=true&sort=id+asc&tv.tf_idf=true&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130125 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&tv.positions=true&df=text&distrib=false&qt=tvrh&fl=id&shards.purpose=4&tv.tf=true&start=0&fsv=true&tv.offsets=true&sort=id+asc&shard.url=http://127.0.0.1:59044//collection1&rows=10&tv.tf_idf=true&version=2&q=id:0&tv.df=true&NOW=1520451114147&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130126 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&tv.positions=true&df=text&distrib=false&qt=tvrh&shards.purpose=64&tv.tf=true&tv.offsets=true&shard.url=http://127.0.0.1:59044//collection1&tv.tf_idf=true&version=2&q=id:0&tv.df=true&NOW=1520451114147&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130126 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=http://127.0.0.1:59044//collection1&tv=true&tv.positions=true&qt=tvrh&tv.tf=true&tv.df=true&tv.offsets=true&sort=id+asc&tv.tf_idf=true&wt=javabin&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 130128 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&tv.all=true&distrib=false&qt=tvrh&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130130 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=id:0&tv.all=true&NOW=1520451114151&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130132 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&shards.purpose=64&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/|[ff01::083]:33332/&version=2&q=id:0&tv.all=true&NOW=1520451114151&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130132 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=http://127.0.0.1:59044//collection1|[ff01::213]:33332/|[ff01::083]:33332/&tv=true&tv.all=true&qt=tvrh&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=2
   [junit4]   2> 130132 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&tv=true&tv.all=true&distrib=false&qt=tvrh&sort=id+desc&distrib.singlePass=true&rows=1&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130134 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*&shards.purpose=68&start=0&fsv=true&sort=id+desc&distrib.singlePass=true&shard.url=http://127.0.0.1:59044//collection1|[ff01::114]:33332/&rows=1&version=2&q=id:0&tv.all=true&NOW=1520451114156&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130134 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:0&shards=http://127.0.0.1:59044//collection1|[ff01::114]:33332/&tv=true&tv.all=true&qt=tvrh&sort=id+desc&distrib.singlePass=true&rows=1&wt=javabin&version=2}
 hits=1 status=0 QTime=1
   [junit4]   2> 130135 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={f.test_basictv.tv.tf_idf=false&tv=true&tv.positions=true&distrib=false&qt=tvrh&f.test_posofftv.tv.positions=false&tv.tf=true&f.test_basictv.tv.df=false&tv.offsets=true&sort=id+desc&tv.fl=test_basictv,test_notv,test_postv,test_offtv,test_posofftv&tv.tf_idf=true&version=2&q=id:0&f.test_offtv.tv.offsets=false&tv.df=true&f.test_basictv.tv.tf=false&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130135 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&fl=id&shards.purpose=4&tv.tf=true&f.test_basictv.tv.df=false&fsv=true&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&wt=javabin&f.test_basictv.tv.tf_idf=false&tv.positions=true&qt=tvrh&f.test_posofftv.tv.positions=false&start=0&tv.offsets=true&sort=id+desc&tv.fl=test_basictv,test_notv,test_postv,test_offtv,test_posofftv&rows=10&tv.tf_idf=true&version=2&q=id:0&f.test_offtv.tv.offsets=false&tv.df=true&NOW=1520451114158&f.test_basictv.tv.tf=false&isShard=true}
 hits=1 status=0 QTime=0
   [junit4]   2> 130140 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={f.test_basictv.tv.tf_idf=false&tv=true&tv.positions=true&df=text&distrib=false&qt=tvrh&f.test_posofftv.tv.positions=false&shards.purpose=64&tv.tf=true&f.test_basictv.tv.df=false&tv.offsets=true&tv.fl=test_basictv,test_notv,test_postv,test_offtv,test_posofftv&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&tv.tf_idf=true&version=2&q=id:0&f.test_offtv.tv.offsets=false&tv.df=true&NOW=1520451114158&f.test_basictv.tv.tf=false&ids=0&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130140 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={f.test_basictv.tv.tf_idf=false&tv=true&tv.positions=true&qt=tvrh&f.test_posofftv.tv.positions=false&tv.tf=true&f.test_basictv.tv.df=false&tv.offsets=true&sort=id+desc&tv.fl=test_basictv,test_notv,test_postv,test_offtv,test_posofftv&tv.tf_idf=true&version=2&q=id:0&shards=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&f.test_offtv.tv.offsets=false&tv.df=true&f.test_basictv.tv.tf=false&wt=javabin}
 hits=1 status=0 QTime=2
   [junit4]   2> 130141 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&distrib=false&qt=tvrh&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130144 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&shards.purpose=4&tv.tf=true&start=0&fsv=true&sort=id+desc&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&rows=10&version=2&q=id:7&NOW=1520451114165&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130145 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&shards.purpose=64&tv.tf=true&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&version=2&q=id:7&NOW=1520451114165&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130146 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&tv=true&qt=tvrh&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 130147 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&distrib=false&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv,test_offtv&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130149 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=32768&tv.tf=true&sort=id+asc&tv.fl=test_basictv,test_offtv&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&version=2&q=id:7&NOW=1520451114171&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130150 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:7,1,1&sort=id+asc&tv.fl=test_basictv,test_offtv&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:7&NOW=1520451114171&isShard=true&org.apache.solr.stats.terms=id:7&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130152 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=64&tv.tf=true&tv.fl=test_basictv,test_offtv&shard.url=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&version=2&q=id:7&NOW=1520451114171&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130153 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=http://127.0.0.1:59044//collection1|[ff01::213]:33332/&tv=true&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv,test_offtv&wt=javabin&version=2}
 hits=1 status=0 QTime=4
   [junit4]   2> 130154 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&distrib=false&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv&tv.fl=test_offtv&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130156 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=32768&tv.tf=true&sort=id+asc&tv.fl=test_basictv&tv.fl=test_offtv&shard.url=[ff01::083]:33332/|http://127.0.0.1:59044//collection1&version=2&q=id:7&NOW=1520451114178&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130157 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:7,1,1&sort=id+asc&tv.fl=test_basictv&tv.fl=test_offtv&shard.url=[ff01::083]:33332/|http://127.0.0.1:59044//collection1&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:7&NOW=1520451114178&isShard=true&org.apache.solr.stats.terms=id:7&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130159 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=64&tv.tf=true&tv.fl=test_basictv&tv.fl=test_offtv&shard.url=[ff01::083]:33332/|http://127.0.0.1:59044//collection1&version=2&q=id:7&NOW=1520451114178&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130159 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=[ff01::083]:33332/|http://127.0.0.1:59044//collection1&tv=true&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv&tv.fl=test_offtv&wt=javabin&version=2}
 hits=1 status=0 QTime=4
   [junit4]   2> 130159 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&distrib=false&qt=tvrh&fl=*,score&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130159 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=32768&tv.tf=true&sort=id+desc&shard.url=http://127.0.0.1:59044//collection1&version=2&q=id:7&NOW=1520451114182&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130164 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:7,1,1&sort=id+desc&shard.url=http://127.0.0.1:59044//collection1&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:7&NOW=1520451114182&isShard=true&org.apache.solr.stats.terms=id:7&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130166 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*,score&shards.purpose=64&tv.tf=true&shard.url=http://127.0.0.1:59044//collection1&version=2&q=id:7&NOW=1520451114182&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130167 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=http://127.0.0.1:59044//collection1&tv=true&qt=tvrh&fl=*,score&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=4
   [junit4]   2> 130168 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&distrib=false&qt=tvrh&fl=score,test_basictv,[docid],test_postv,val:sum(3,4)&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130169 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=score,test_basictv,[docid],test_postv,val:sum(3,4)&shards.purpose=32768&tv.tf=true&sort=id+desc&shard.url=http://127.0.0.1:59044//collection1&version=2&q=id:7&NOW=1520451114192&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130171 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:7,1,1&sort=id+desc&shard.url=http://127.0.0.1:59044//collection1&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:7&NOW=1520451114192&isShard=true&org.apache.solr.stats.terms=id:7&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130172 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=score,test_basictv,[docid],test_postv,val:sum(3,4)&fl=id&shards.purpose=64&tv.tf=true&shard.url=http://127.0.0.1:59044//collection1&version=2&q=id:7&NOW=1520451114192&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130173 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=http://127.0.0.1:59044//collection1&tv=true&qt=tvrh&fl=score,test_basictv,[docid],test_postv,val:sum(3,4)&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=4
   [junit4]   2> 130174 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&distrib=false&qt=tvrh&fl=score,test_basictv&fl=[docid],test_postv,val:sum(3,4)&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130175 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=score,test_basictv&fl=[docid],test_postv,val:sum(3,4)&shards.purpose=32768&tv.tf=true&sort=id+desc&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:59044//collection1&version=2&q=id:7&NOW=1520451114198&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130177 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&fl=score&shards.purpose=16388&tv.tf=true&start=0&fsv=true&org.apache.solr.stats.termStats=id:7,1,1&sort=id+desc&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:59044//collection1&rows=10&version=2&org.apache.solr.stats.colStats=id,10,10,10,10&q=id:7&NOW=1520451114198&isShard=true&org.apache.solr.stats.terms=id:7&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130177 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=score,test_basictv&fl=[docid],test_postv,val:sum(3,4)&fl=id&shards.purpose=64&tv.tf=true&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:59044//collection1&version=2&q=id:7&NOW=1520451114198&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130178 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:59044//collection1&tv=true&qt=tvrh&fl=score,test_basictv&fl=[docid],test_postv,val:sum(3,4)&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 130179 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&tv.positions=true&distrib=false&qt=tvrh&tv.tf=true&tv.df=true&tv.offsets=true&sort=id+asc&tv.tf_idf=true&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130181 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&tv.positions=true&df=text&distrib=false&qt=tvrh&fl=id&shards.purpose=4&tv.tf=true&start=0&fsv=true&tv.offsets=true&sort=id+asc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&rows=10&tv.tf_idf=true&version=2&q=id:7&tv.df=true&NOW=1520451114203&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130183 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&tv.positions=true&df=text&distrib=false&qt=tvrh&shards.purpose=64&tv.tf=true&tv.offsets=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&tv.tf_idf=true&version=2&q=id:7&tv.df=true&NOW=1520451114203&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130183 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/&tv=true&tv.positions=true&qt=tvrh&tv.tf=true&tv.df=true&tv.offsets=true&sort=id+asc&tv.tf_idf=true&wt=javabin&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 130183 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&tv.all=true&distrib=false&qt=tvrh&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130183 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=id:7&tv.all=true&NOW=1520451114206&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130188 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&shards.purpose=64&shard.url=[ff01::114]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/|[ff01::083]:33332/&version=2&q=id:7&tv.all=true&NOW=1520451114206&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130188 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=[ff01::114]:33332/|http://127.0.0.1:59044//collection1|[ff01::213]:33332/|[ff01::083]:33332/&tv=true&tv.all=true&qt=tvrh&sort=id+desc&wt=javabin&version=2}
 hits=1 status=0 QTime=2
   [junit4]   2> 130190 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&tv=true&tv.all=true&distrib=false&qt=tvrh&sort=id+desc&distrib.singlePass=true&rows=1&wt=javabin&version=2}
 hits=1 status=0 QTime=0
   [junit4]   2> 130191 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=*&shards.purpose=68&start=0&fsv=true&sort=id+desc&distrib.singlePass=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=1&version=2&q=id:7&tv.all=true&NOW=1520451114214&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130192 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:7&shards=[ff01::083]:33332/|http://127.0.0.1:59044//collection1|[ff01::114]:33332/|[ff01::213]:33332/&tv=true&tv.all=true&qt=tvrh&sort=id+desc&distrib.singlePass=true&rows=1&wt=javabin&version=2}
 hits=1 status=0 QTime=1
   [junit4]   2> 130193 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={f.test_basictv.tv.tf_idf=false&tv=true&tv.positions=true&distrib=false&qt=tvrh&f.test_posofftv.tv.positions=false&tv.tf=true&f.test_basictv.tv.df=false&tv.offsets=true&sort=id+desc&tv.fl=test_basictv,test_notv,test_postv,test_offtv,test_posofftv&tv.tf_idf=true&version=2&q=id:7&f.test_offtv.tv.offsets=false&tv.df=true&f.test_basictv.tv.tf=false&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 130195 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&fl=id&shards.purpose=4&tv.tf=true&f.test_basictv.tv.df=false&fsv=true&shard.url=http://127.0.0.1:59044//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&f.test_basictv.tv.tf_idf=false&tv.positions=true&qt=tvrh&f.test_posofftv.tv.positions=false&start=0&tv.offsets=true&sort=id+desc&tv.fl=test_basictv,test_notv,test_postv,test_offtv,test_posofftv&rows=10&tv.tf_idf=true&version=2&q=id:7&f.test_offtv.tv.offsets=false&tv.df=true&NOW=1520451114218&f.test_basictv.tv.tf=false&isShard=true}
 hits=1 status=0 QTime=0
   [junit4]   2> 130197 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={f.test_basictv.tv.tf_idf=false&tv=true&tv.positions=true&df=text&distrib=false&qt=tvrh&f.test_posofftv.tv.positions=false&shards.purpose=64&tv.tf=true&f.test_basictv.tv.df=false&tv.offsets=true&tv.fl=test_basictv,test_notv,test_postv,test_offtv,test_posofftv&shard.url=http://127.0.0.1:59044//collection1|[ff01::114]:33332/|[ff01::083]:33332/&tv.tf_idf=true&version=2&q=id:7&f.test_offtv.tv.offsets=false&tv.df=true&NOW=1520451114218&f.test_basictv.tv.tf=false&ids=7&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130198 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={f.test_basictv.tv.tf_idf=false&tv=true&tv.positions=true&qt=tvrh&f.test_posofftv.tv.positions=false&tv.tf=true&f.test_basictv.tv.df=false&tv.offsets=true&sort=id+desc&tv.fl=test_basictv,test_notv,test_postv,test_offtv,test_posofftv&tv.tf_idf=true&version=2&q=id:7&shards=http://127.0.0.1:59044//collection1|[ff01::114]:33332/|[ff01::083]:33332/&f.test_offtv.tv.offsets=false&tv.df=true&f.test_basictv.tv.tf=false&wt=javabin}
 hits=1 status=0 QTime=3
   [junit4]   2> 130203 INFO  (qtp21445109-3284) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:[3+TO+6]&tv=true&distrib=false&qt=tvrh&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=4 status=0 QTime=4
   [junit4]   2> 130205 INFO  (qtp13112278-3308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&fl=id&shards.purpose=4&tv.tf=true&start=0&fsv=true&sort=id+desc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:59044//collection1|[ff01::083]:33332/&rows=10&version=2&q=id:[3+TO+6]&NOW=1520451114227&isShard=true&wt=javabin}
 hits=4 status=0 QTime=0
   [junit4]   2> 130207 INFO  (qtp13112278-3306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={tv=true&df=text&distrib=false&qt=tvrh&shards.purpose=64&tv.tf=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:59044//collection1|[ff01::083]:33332/&version=2&q=id:[3+TO+6]&NOW=1520451114227&ids=3,4,5,6&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 130209 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:[3+TO+6]&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:59044//collection1|[ff01::083]:33332/&tv=true&qt=tvrh&tv.tf=true&sort=id+desc&wt=javabin&version=2}
 hits=4 status=0 QTime=5
   [junit4]   2> 130211 INFO  (qtp21445109-3286) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=id:[3+TO+6]&tv=true&distrib=false&qt=tvrh&fl=*,score&tv.tf=true&sort=id+asc&tv.fl=test_basictv,test_offtv&wt=javabin&version=2}
 hits=4 status=0 QTime=0
   [junit4]   2> 130213 INFO  (qtp13112278-3301) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select params={tv=true&df=text&d

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

) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=29858722
   [junit4]   2> 1051159 INFO  
(SUITE-TestChildDocTransformer-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1051159 INFO  
(SUITE-TestChildDocTransformer-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1fb16a4: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@d5e5ae
   [junit4]   2> 1051162 INFO  
(SUITE-TestChildDocTransformer-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1051163 INFO  
(SUITE-TestChildDocTransformer-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1455574: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@14c0075
   [junit4]   2> 1051167 INFO  
(SUITE-TestChildDocTransformer-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1051167 INFO  
(SUITE-TestChildDocTransformer-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1142fd4: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@18f451a
   [junit4]   2> 1051169 INFO  (coreCloseExecutor-3186-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1c2e02f
   [junit4]   2> 1051170 INFO  (coreCloseExecutor-3186-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=29548591
   [junit4]   2> 1051170 INFO  (coreCloseExecutor-3186-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@34927c: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@198058d
   [junit4]   2> 1051183 INFO  
(SUITE-TestChildDocTransformer-seed#[E3CB946A23D22B1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{title_stringNoNorms=FSTOrd50, title_stemmed=BlockTreeOrds(blocksize=128), 
multiDefault=FSTOrd50, subject=FSTOrd50, cat=FSTOrd50, 
_root_=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=MockRandom), 
text=PostingsFormat(name=LuceneVarGapFixedInterval), 
title=BlockTreeOrds(blocksize=128), 
title_lettertok=PostingsFormat(name=MockRandom)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), 
_version_=DocValuesFormat(name=Lucene70), 
intDefault=DocValuesFormat(name=Lucene70), 
id_i1=DocValuesFormat(name=Lucene70), 
range_facet_i_dv=DocValuesFormat(name=Asserting), 
intDvoDefault=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Asserting), 
range_facet_l=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1173, 
maxMBSortInHeap=5.199745320468498, sim=RandomSimilarity(queryNorm=true): {}, 
locale=el-GR, timezone=Europe/Zurich
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=189787384,total=373293056
   [junit4]   2> NOTE: All tests run in this JVM: [CleanupOldIndexTest, 
TestFuzzyAnalyzedSuggestions, JsonLoaderTest, TestManagedSchemaAPI, 
TestFileDictionaryLookup, TestSQLHandler, TestQueryWrapperFilter, 
ImplicitSnitchTest, AnalysisErrorHandlingTest, TestPrepRecovery, 
ReturnFieldsTest, DistributedTermsComponentTest, TestPullReplicaErrorHandling, 
TestTrieFacet, SearchHandlerTest, TestPerFieldSimilarityWithDefaultOverride, 
TestFieldResource, CoreAdminHandlerTest, TestStandardQParsers, 
ResponseLogComponentTest, DocValuesTest, TestNRTOpen, TestCloudInspectUtil, 
DistributedQueryComponentCustomSortTest, TestBackupRepositoryFactory, 
MinimalSchemaTest, CurrencyRangeFacetCloudTest, TestInfoStreamLogging, 
TestSolrCloudWithDelegationTokens, TestFastWriter, SolrMetricManagerTest, 
TestAddFieldRealTimeGet, TestMacros, SpellCheckComponentTest, 
TestSolrCLIRunExample, StressHdfsTest, ChaosMonkeyNothingIsSafeTest, 
SpellCheckCollatorTest, TestJettySolrRunner, DirectoryFactoryTest, 
DefaultValueUpdateProcessorTest, TestLeaderElectionWithEmptyReplica, 
TestCustomSort, NumberUtilsTest, TestCSVLoader, TestDistributedGrouping, 
URLClassifyProcessorTest, TestBadConfig, TestSortByMinMaxFunction, 
ZkStateReaderTest, TestInPlaceUpdatesDistrib, TestFieldCacheSortRandom, 
TestNumericTerms32, TestUniqueKeyFieldResource, TestXmlQParserPlugin, 
LoggingHandlerTest, TestCloudConsistency, TestUpdate, TestCrossCoreJoin, 
DeleteInactiveReplicaTest, TestMiniSolrCloudClusterSSL, 
XsltUpdateRequestHandlerTest, TestRequestForwarding, ZkShardTermsTest, 
FieldAnalysisRequestHandlerTest, TestStressRecovery, TestClusterStateProvider, 
TestSolrConfigHandler, DocValuesMultiTest, TestAnalyzedSuggestions, 
BitVectorTest, BadCopyFieldTest, TestJsonRequest, 
TestMultiValuedNumericRangeQuery, SortByFunctionTest, TimeZoneUtilsTest, 
TestQuerySenderListener, ChaosMonkeySafeLeaderTest, OverseerRolesTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestSolrIndexConfig, 
TestBulkSchemaConcurrent, TestDistributedMissingSort, DeleteNodeTest, 
SuggesterTest, FullHLLTest, DistanceFunctionTest, 
TestLocalFSCloudBackupRestore, TestChildDocTransformer]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestChildDocTransformer -Dtests.seed=E3CB946A23D22B1 
-Dtests.slow=true -Dtests.locale=el-GR -Dtests.timezone=Europe/Zurich 
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | TestChildDocTransformer (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog\tlog.0000000000000000001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001\init-core-data-001\tlog\tlog.0000000000000000001
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.response.transform.TestChildDocTransformer_E3CB946A23D22B1-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E3CB946A23D22B1]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [209/780 (3!)] on J1 in 0.76s, 2 tests, 1 error <<< 
FAILURES!

[...truncated 47230 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/489/consoleText

[repro] Revision: dc9b511f7815c8a95e88d55e523e9c28e3299a3a

[repro] Ant options: "-Dargs=-server -XX:+UseParallelGC"

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    lucene\spatial-extras
[repro]       DateNRStrategyTest
[repro]    solr\core
[repro]       TestChildDocTransformer
[repro]       TermVectorComponentDistributedTest
[repro]       DistributedTermsComponentTest
[repro] ant compile-test

[...truncated 380 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.DateNRStrategyTest" -Dtests.showOutput=onerror "-Dargs=-server 
-XX:+UseParallelGC" -Dtests.seed=B4DBD589001F91F0 -Dtests.slow=true 
-Dtests.locale=ja-JP -Dtests.timezone=America/Miquelon -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[...truncated 234 lines...]
[repro] ant compile-test

[...truncated 3193 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=15 
-Dtests.class="*.TestChildDocTransformer|*.TermVectorComponentDistributedTest|*.DistributedTermsComponentTest"
 -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseParallelGC" 
-Dtests.seed=E3CB946A23D22B1 -Dtests.slow=true -Dtests.locale=el-GR 
-Dtests.timezone=Europe/Zurich -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII

[...truncated 116 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.lucene.spatial.prefix.DateNRStrategyTest
[repro]   0/5 failed: 
org.apache.solr.handler.component.DistributedTermsComponentTest
[repro]   0/5 failed: 
org.apache.solr.handler.component.TermVectorComponentDistributedTest
[repro]   0/5 failed: org.apache.solr.response.transform.TestChildDocTransformer
[repro] Exiting with code 0

[...truncated 75 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org