Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/495/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC

4 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.lucene.store.TestNIOFSDirectory

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001\testThreadSafety-001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001\testThreadSafety-001
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-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\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001\testThreadSafety-001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001\testThreadSafety-001
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001

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


FAILED:  
org.apache.lucene.replicator.IndexReplicationClientTest.testConsistencyOnExceptions

Error Message:
Captured an uncaught exception in thread: Thread[id=14, 
name=ReplicationThread-index, state=RUNNABLE, 
group=TGRP-IndexReplicationClientTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=14, name=ReplicationThread-index, 
state=RUNNABLE, group=TGRP-IndexReplicationClientTest]
        at 
__randomizedtesting.SeedInfo.seed([5F6555E20FF46A36:D0EBB2421D9899C9]:0)
Caused by: java.lang.AssertionError: handler failed too many times: -1
        at __randomizedtesting.SeedInfo.seed([5F6555E20FF46A36]:0)
        at 
org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
        at 
org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)


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

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.search.TestSmileRequest_8A15200616B7C39C-001\s2-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-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.search.TestSmileRequest_8A15200616B7C39C-001\s2-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001

        at __randomizedtesting.SeedInfo.seed([8A15200616B7C39C]: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.client.solrj.io.graph.GraphTest

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-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data\tlog
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-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-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data\tlog
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001

        at __randomizedtesting.SeedInfo.seed([B0536CB0BB5E1436]: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 1711 lines...]
   [junit4] Suite: org.apache.lucene.store.TestNIOFSDirectory
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=RandomSimilarity(queryNorm=false): {}, locale=cs, timezone=Pacific/Gambier
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=230498560,total=382066688
   [junit4]   2> NOTE: All tests run in this JVM: [TestBooleanOr, 
TestReusableStringReader, TermInSetQueryTest, TestSpanNotQuery, 
TestDocsAndPositions, TestBagOfPostings, TestWordlistLoader, 
TestDoubleRangeFieldQueries, TestDateSort, TestCharacterUtils, 
TestParallelCompositeReader, TestPackedTokenAttributeImpl, TestHugeRamFile, 
TestNeverDelete, TestTieredMergePolicy, TestFilterIterator, 
TestSnapshotDeletionPolicy, TestNumericUtils, 
TestUsageTrackingFilterCachingPolicy, TestComplexExplanations, TestIndexInput, 
TestParallelReaderEmptyIndex, TestSimpleAttributeImpl, TestDirectoryReader, 
TestFixedLengthBytesRefArray, TestCustomNorms, TestManyFields, TestVersion, 
TestSegmentTermEnum, TestIndexWriterMergePolicy, TestIndexWriterExceptions, 
TestBufferedChecksum, TestReaderWrapperDVTypeCheck, 
TestFieldCacheRewriteMethod, TestBlockPostingsFormat2, TestIndexManyDocuments, 
TestDocInverterPerFieldErrorInfo, TestDocument, TestDocIdSetBuilder, 
TestInPlaceMergeSorter, TestFieldInfos, TestBooleanQuery, 
TestRecyclingByteBlockAllocator, TestNeedsScores, TestMinimize, 
TestAttributeSource, TestMergePolicyWrapper, TestLSBRadixSorter, 
TestFieldsReader, TestParallelLeafReader, TestPrefixRandom, 
TestSpanExplanationsOfNonMatches, TestQueryBuilder, TestAtomicUpdate, 
TestNorms, TestSort, TestOperations, TestIntRange, TestIndexSearcher, 
TestNRTReaderCleanup, TestBagOfPositions, TestSloppyMath, TestPriorityQueue, 
TestConjunctionDISI, TestExceedMaxTermLength, TestNewestSegment, 
TestMixedCodecs, TestNot, TestSameTokenSamePosition, TestPositionIncrement, 
TestCodecUtil, TestFieldInvertState, TestLRUQueryCache, TestLiveFieldValues, 
TestRecyclingIntBlockAllocator, TestTopDocsMerge, TestTermVectors, 
TestDocIdsWriter, TestMinShouldMatch2, TestScoreCachingWrappingScorer, 
TestConsistentFieldNumbers, Test2BTerms, TestLucene50TermVectorsFormat, 
TestLucene70DocValuesFormat, TestBooleanQueryVisitSubscorers, 
TestUpgradeIndexMergePolicy, TestRamUsageEstimator, TestSameScoresWithThreads, 
TestIndexWriter, TestOneMergeWrappingMergePolicy, TestAllFilesHaveCodecHeader, 
TestLucene70SegmentInfoFormat, TestCollectionUtil, TestBytesRefArray, TestDoc, 
TestMSBRadixSorter, TestInfoStream, TestPhrasePrefixQuery, TestDuelingCodecs, 
TestGeoEncodingUtils, TestPointValues, TestBufferedIndexInput, TestCodecs, 
TestMultiPhraseEnum, TestSparseFixedBitSet, TestSwappedIndexFiles, 
TestLucene50StoredFieldsFormat, TestGrowableByteArrayDataOutput, 
TestTragicIndexWriterDeadlock, TestSortedNumericSortField, TestRegexpQuery, 
TestToken, TestCrashCausesCorruptIndex, TestArrayUtil, TestDocCount, 
TestSumDocFreq, TestDeletionPolicy, TestSloppyPhraseQuery, 
TestIndexWriterDelete, TestIndexWriterCommit, TestDirectoryReaderReopen, 
TestTimeLimitingCollector, TestSearchWithThreads, TestHighCompressionMode, 
TestDocumentsWriterStallControl, TestLongPostings, TestRollingUpdates, 
TestIndexWriterForceMerge, TestLockFactory, TestSegmentMerger, TestOmitNorms, 
TestSegmentReader, TestTermScorer, TestIndexableField, TestRegexpRandom, 
TestCheckIndex, TestSearchForDuplicates, TestIndexFileDeleter, 
TestMatchAllDocsQuery, TestElevationComparator, TestBinaryTerms, 
TestConjunctions, TestSimilarity, TestVirtualMethod, TestFilterLeafReader, 
TestDateTools, Test2BPostings, TestAutomatonQueryUnicode, TestBooleanScorer, 
TestNamedSPILoader, TestBytesRef, TestAssertions, TestRollback, 
Test4GBStoredFields, TestReaderClosed, TestTermdocPerf, TestTerm, 
TestByteArrayDataInput, Test2BPagedBytes, TestCharArrayMap, TestStopFilter, 
TestForUtil, TestLucene60PointsFormat, TestFieldType, TestGeoUtils, 
TestCustomTermFreq, TestDemoParallelLeafReader, TestIndexTooManyDocs, 
TestIndexWriterDeleteByQuery, TestIndexWriterExceptions2, 
TestIndexWriterFromReader, TestIndexWriterOnVMError, 
TestIndexWriterOutOfFileDescriptors, TestIndexWriterThreadsToSegments, 
TestIndexingSequenceNumbers, TestLongRangeFieldQueries, TestPointQueries, 
TestSortRescorer, TestSortedSetSortField, TestSynonymQuery, TestTermQuery, 
TestTopFieldCollectorEarlyTermination, TestAxiomaticSimilarity, 
TestBM25Similarity, TestClassicSimilarity, TestFilterSpans, TestSpanBoostQuery, 
TestSpanContainQuery, TestSpanNearQuery, TestSpanTermQuery, TestNIOFSDirectory]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestNIOFSDirectory 
-Dtests.seed=EF9143CB356036BF -Dtests.slow=true -Dtests.locale=cs 
-Dtests.timezone=Pacific/Gambier -Dtests.asserts=true 
-Dtests.file.encoding=Cp1252
   [junit4] ERROR   0.00s J0 | TestNIOFSDirectory (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\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001\testThreadSafety-001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001\testThreadSafety-001
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestNIOFSDirectory_EF9143CB356036BF-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([EF9143CB356036BF]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [441/466 (1!)] on J0 in 2.97s, 42 tests, 1 error <<< 
FAILURES!

[...truncated 7528 lines...]
   [junit4] Suite: org.apache.lucene.replicator.IndexReplicationClientTest
   [junit4]   2> mar 10, 2018 5:27:13 PM 
com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler
 uncaughtException
   [junit4]   2> ADVERTENCIA: Uncaught exception in thread: 
Thread[ReplicationThread-index,5,TGRP-IndexReplicationClientTest]
   [junit4]   2> java.lang.AssertionError: handler failed too many times: -1
   [junit4]   2>        at 
__randomizedtesting.SeedInfo.seed([5F6555E20FF46A36]:0)
   [junit4]   2>        at 
org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
   [junit4]   2>        at 
org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=IndexReplicationClientTest 
-Dtests.method=testConsistencyOnExceptions -Dtests.seed=5F6555E20FF46A36 
-Dtests.slow=true -Dtests.locale=es-EC -Dtests.timezone=Antarctica/Davis 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.73s J0 | 
IndexReplicationClientTest.testConsistencyOnExceptions <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=14, name=ReplicationThread-index, 
state=RUNNABLE, group=TGRP-IndexReplicationClientTest]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([5F6555E20FF46A36:D0EBB2421D9899C9]:0)
   [junit4]    > Caused by: java.lang.AssertionError: handler failed too many 
times: -1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([5F6555E20FF46A36]:0)
   [junit4]    >        at 
org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
   [junit4]    >        at 
org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=2022, maxMBSortInHeap=6.647428564234554, 
sim=RandomSimilarity(queryNorm=false): {}, locale=es-EC, 
timezone=Antarctica/Davis
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=5204368,total=16252928
   [junit4]   2> NOTE: All tests run in this JVM: [TestStressNRTReplication, 
IndexReplicationClientTest]
   [junit4] Completed [6/9 (1!)] on J0 in 2.07s, 4 tests, 1 error <<< FAILURES!

[...truncated 5467 lines...]
   [junit4] Suite: org.apache.solr.search.TestSmileRequest
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\init-core-data-001
   [junit4]   2> 3164594 WARN  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 3164594 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 3164599 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 3164599 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 3164600 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 3164601 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
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> 3164657 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 3164675 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=example
   [junit4]   2> 3164737 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 3164815 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3164823 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3164823 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3164825 INFO  (coreLoadExecutor-13856-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 3164826 INFO  (coreLoadExecutor-13856-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> 3164879 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 3164894 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 3164958 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 3164968 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 3164968 INFO  (coreLoadExecutor-13856-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@194e6eb
   [junit4]   2> 3164968 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3164968 INFO  (coreLoadExecutor-13856-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\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\init-core-data-001\]
   [junit4]   2> 3164971 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=11, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=10.517578125, 
floorSegmentMB=2.0146484375, forceMergeDeletesPctAllowed=14.064572290946955, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.16670516014718756
   [junit4]   2> 3164974 WARN  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3165050 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3165050 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 3165051 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3165051 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3165052 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1023914571, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 3165053 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f4ec1e[collection1] 
main]
   [junit4]   2> 3165054 WARN  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 3165055 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3165056 INFO  
(searcherExecutor-13857-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f4ec1e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3165056 INFO  (coreLoadExecutor-13856-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1594549572263215104
   [junit4]   2> 3165059 INFO  
(SUITE-TestSmileRequest-seed#[8A15200616B7C39C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 3165062 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testDistribJsonRequest
   [junit4]   2> 3165079 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2560-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 3165082 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 3165082 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 3165082 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 3165082 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@c6145c{/solr,null,AVAILABLE}
   [junit4]   2> 3165083 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@128f210{HTTP/1.1,[http/1.1]}{127.0.0.1:52247}
   [junit4]   2> 3165083 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.Server Started @3172520ms
   [junit4]   2> 3165083 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, 
solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=52247}
   [junit4]   2> 3165084 ERROR 
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3165084 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 3165085 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 3165085 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3165085 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-10T11:22:53.333Z
   [junit4]   2> 3165085 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s0-001\solr.xml
   [junit4]   2> 3165093 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 3165093 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 3165094 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 3165170 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165182 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165182 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165188 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s0-001\.
   [junit4]   2> 3165188 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 3165191 INFO  (coreLoadExecutor-13867-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 3165222 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 3165242 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 3165304 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 3165313 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s0-001\.\collection1,
 trusted=true
   [junit4]   2> 3165313 INFO  (coreLoadExecutor-13867-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@194e6eb
   [junit4]   2> 3165313 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3165313 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s0-001\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s0-001\.\collection1\data\]
   [junit4]   2> 3165317 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=11, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=10.517578125, 
floorSegmentMB=2.0146484375, forceMergeDeletesPctAllowed=14.064572290946955, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.16670516014718756
   [junit4]   2> 3165320 WARN  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3165398 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3165398 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 3165398 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3165398 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3165402 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1023914571, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 3165402 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a2cb28[collection1] 
main]
   [junit4]   2> 3165402 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s0-001\collection1\conf
   [junit4]   2> 3165403 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3165406 INFO  
(searcherExecutor-13868-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a2cb28[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3165406 INFO  (coreLoadExecutor-13867-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1594549572630216704
   [junit4]   2> 3165408 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.SolrTestCaseHS ===> Started solr server port=52247 
home=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s0-001
   [junit4]   2> 3165435 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2560-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 3165435 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 3165435 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 3165436 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 3165436 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1a3be29{/solr,null,AVAILABLE}
   [junit4]   2> 3165437 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@826606{HTTP/1.1,[http/1.1]}{127.0.0.1:52250}
   [junit4]   2> 3165437 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.Server Started @3172874ms
   [junit4]   2> 3165437 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, 
solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=52250}
   [junit4]   2> 3165438 ERROR 
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3165438 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 3165438 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 3165439 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3165439 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-10T11:22:53.687Z
   [junit4]   2> 3165440 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s1-001\solr.xml
   [junit4]   2> 3165448 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 3165448 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 3165448 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 3165534 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165548 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165549 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165555 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s1-001\.
   [junit4]   2> 3165555 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 3165556 INFO  (coreLoadExecutor-13878-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 3165588 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 3165601 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 3165663 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 3165673 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s1-001\.\collection1,
 trusted=true
   [junit4]   2> 3165673 INFO  (coreLoadExecutor-13878-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@194e6eb
   [junit4]   2> 3165673 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3165673 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s1-001\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s1-001\.\collection1\data\]
   [junit4]   2> 3165676 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=11, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=10.517578125, 
floorSegmentMB=2.0146484375, forceMergeDeletesPctAllowed=14.064572290946955, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.16670516014718756
   [junit4]   2> 3165679 WARN  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3165763 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3165763 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 3165765 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3165765 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3165766 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1023914571, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 3165766 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13949ea[collection1] 
main]
   [junit4]   2> 3165767 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s1-001\collection1\conf
   [junit4]   2> 3165768 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3165770 INFO  
(searcherExecutor-13879-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@13949ea[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3165770 INFO  (coreLoadExecutor-13878-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1594549573011898368
   [junit4]   2> 3165771 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.SolrTestCaseHS ===> Started solr server port=52250 
home=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s1-001
   [junit4]   2> 3165789 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2560-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 3165790 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 3165790 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 3165790 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 3165790 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@a6f5e5{/solr,null,AVAILABLE}
   [junit4]   2> 3165791 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@38afe{HTTP/1.1,[http/1.1]}{127.0.0.1:52253}
   [junit4]   2> 3165791 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.e.j.s.Server Started @3173228ms
   [junit4]   2> 3165791 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, 
solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=52253}
   [junit4]   2> 3165792 ERROR 
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 3165792 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 3165792 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 3165792 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 3165792 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-10T11:22:54.040Z
   [junit4]   2> 3165793 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001\solr.xml
   [junit4]   2> 3165800 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 3165800 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 3165802 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 3165881 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165892 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165893 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194e6eb
   [junit4]   2> 3165899 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001\.
   [junit4]   2> 3165899 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 3165901 INFO  (coreLoadExecutor-13889-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 3165928 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 3165939 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 3166007 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 3166017 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001\.\collection1,
 trusted=true
   [junit4]   2> 3166017 INFO  (coreLoadExecutor-13889-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@194e6eb
   [junit4]   2> 3166017 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 3166017 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001\.\collection1\data\]
   [junit4]   2> 3166019 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=11, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=10.517578125, 
floorSegmentMB=2.0146484375, forceMergeDeletesPctAllowed=14.064572290946955, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.16670516014718756
   [junit4]   2> 3166022 WARN  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 3166099 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 3166099 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 3166101 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 3166101 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 3166102 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1023914571, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 3166102 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bbf5c8[collection1] 
main]
   [junit4]   2> 3166103 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001\collection1\conf
   [junit4]   2> 3166104 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 3166104 INFO  (coreLoadExecutor-13889-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1594549573362122752
   [junit4]   2> 3166105 INFO  
(TEST-TestSmileRequest.testDistribJsonRequest-seed#[8A15200616B7C39C]) [    ] 
o.a.s.SolrTestCaseHS ===> Started solr server port=52253 
home=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSmileRequest_8A15200616B7C39C-001\s2-001
   [junit4]   2> 3166105 INFO  
(searcherExecutor-13890-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1bbf5c8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 3166118 INFO  (qtp15798085-34286) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1594549573374705664)} 0 2
   [junit4]   2> 3166124 INFO  (qtp3829833-34302) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1594549573380997120)} 0 2
   [junit4]   2> 3166130 INFO  (qtp30172609-34318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1594549573387288576)} 0 2
   [junit4]   2> 3166132 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1 (1594549573391482880)]} 0 0
   [junit4]   2> 3166134 INFO  (qtp15798085-34290) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2 (1594549573393580032)]} 0 0
   [junit4]   2> 3166135 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[3 (1594549573394628608)]} 0 0
   [junit4]   2> 3166136 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573395677184,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166136 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@c78b01 
commitCommandVersion:1594549573395677184
   [junit4]   2> 3166139 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@10a911b[collection1] main]
   [junit4]   2> 3166139 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166142 INFO  
(searcherExecutor-13868-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@10a911b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C2)))}
   [junit4]   2> 3166142 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 3166144 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573404065792,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166144 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@13a495a 
commitCommandVersion:1594549573404065792
   [junit4]   2> 3166146 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@146341a[collection1] main]
   [junit4]   2> 3166147 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166165 INFO  
(searcherExecutor-13879-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@146341a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C1)))}
   [junit4]   2> 3166165 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 21
   [junit4]   2> 3166166 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573427134464,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166167 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 3166167 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166167 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 0
   [junit4]   2> 3166169 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[4 (1594549573429231616)]} 0 1
   [junit4]   2> 3166173 INFO  (qtp30172609-34322) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[5 (1594549573432377344)]} 0 2
   [junit4]   2> 3166175 INFO  (qtp15798085-34290) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573436571648,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166175 INFO  (qtp15798085-34290) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 3166177 INFO  (qtp15798085-34290) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166177 INFO  (qtp15798085-34290) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 2
   [junit4]   2> 3166178 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573439717376,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166178 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@13a495a 
commitCommandVersion:1594549573439717376
   [junit4]   2> 3166181 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@11a11be[collection1] main]
   [junit4]   2> 3166184 INFO  
(searcherExecutor-13879-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@11a11be[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C1)
 Uninverting(_1(7.3.0):C1)))}
   [junit4]   2> 3166185 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166185 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 3166186 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573448105984,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166186 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ec198e 
commitCommandVersion:1594549573448105984
   [junit4]   2> 3166188 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1706fe[collection1] main]
   [junit4]   2> 3166189 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166192 INFO  
(searcherExecutor-13890-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1706fe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C1)))}
   [junit4]   2> 3166192 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 3166196 INFO  (qtp30172609-34322) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[6 (1594549573455446016)]} 0 2
   [junit4]   2> 3166197 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573459640320,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166197 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 3166198 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166198 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 0
   [junit4]   2> 3166198 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573460688896,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166199 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 3166199 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166199 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 0
   [junit4]   2> 3166200 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1594549573462786048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 3166200 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@ec198e 
commitCommandVersion:1594549573462786048
   [junit4]   2> 3166206 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@dc16[collection1] main]
   [junit4]   2> 3166209 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 3166209 INFO  
(searcherExecutor-13890-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@dc16[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C1)
 Uninverting(_1(7.3.0):C1)))}
   [junit4]   2> 3166210 INFO  (qtp30172609-34320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 9
   [junit4]   2> 3166218 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974459&json={query:'cat_s:A'}&isShard=true&wt=javabin}
 hits=1 status=0 QTime=1
   [junit4]   2> 3166221 INFO  (qtp30172609-34323) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974459&json={query:'cat_s:A'}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166223 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974459&json={query:'cat_s:A'}&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166226 INFO  (qtp15798085-34290) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=cat_s:A&df=text&distrib=false&shards.purpose=64&NOW=1520680974459&json={query:'cat_s:A'}&ids=1&isShard=true&shard.url=127.0.0.1:52247/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166226 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=cat_s:A&df=text&distrib=false&shards.purpose=64&NOW=1520680974459&json={query:'cat_s:A'}&ids=4&isShard=true&shard.url=127.0.0.1:52250/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166229 INFO  (qtp30172609-34322) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json={query:'cat_s:A'}&wt=smile&version=2}
 hits=2 status=0 QTime=18
   [junit4]   2> 3166238 INFO  (qtp3829833-34306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974481&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=1
   [junit4]   2> 3166242 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974481&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166244 INFO  (qtp30172609-34322) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974481&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=1
   [junit4]   2> 3166247 INFO  (qtp15798085-34292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=cat_s:A&df=text&distrib=false&shards.purpose=64&NOW=1520680974481&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&ids=1&isShard=true&fq=where_s:NY&shard.url=127.0.0.1:52247/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166248 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&wt=smile&version=2}
 hits=1 status=0 QTime=14
   [junit4]   2> 3166253 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974497&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166257 INFO  (qtp30172609-34322) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974497&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166258 INFO  (qtp3829833-34307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974497&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166260 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=cat_s:A&df=text&distrib=false&shards.purpose=64&NOW=1520680974497&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=&ids=1&isShard=true&fq=where_s:NY&shard.url=127.0.0.1:52247/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166261 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=&wt=smile&version=2}
 hits=1 status=0 QTime=11
   [junit4]   2> 3166265 INFO  (qtp30172609-34324) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974511&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=/*+*/&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166265 INFO  (qtp3829833-34308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974511&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=/*+*/&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166266 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=cat_s:A&NOW=1520680974511&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=/*+*/&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166267 INFO  (qtp15798085-34292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=cat_s:A&df=text&distrib=false&shards.purpose=64&NOW=1520680974511&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=/*+*/&ids=1&isShard=true&fq=where_s:NY&shard.url=127.0.0.1:52247/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166268 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&json=/*+*/&wt=smile&version=2}
 hits=1 status=0 QTime=4
   [junit4]   2> 3166271 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974517&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:'cat_s:A'}&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166271 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974517&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:'cat_s:A'}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166273 INFO  (qtp30172609-34324) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974517&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:'cat_s:A'}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166274 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520680974517&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:'cat_s:A'}&ids=1&isShard=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166275 INFO  (qtp30172609-34322) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:'cat_s:A'}&wt=smile&version=2}
 hits=1 status=0 QTime=5
   [junit4]   2> 3166278 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974525&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:['cat_s:A']}&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166278 INFO  (qtp30172609-34323) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974525&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:['cat_s:A']}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166278 INFO  (qtp3829833-34308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974525&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:['cat_s:A']}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166280 INFO  (qtp15798085-34292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520680974525&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:['cat_s:A']}&ids=1&isShard=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166280 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json={query:'*:*'}&json={filter:'where_s:NY'}&json={filter:['cat_s:A']}&wt=smile&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 3166283 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974530&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:'cat_s:A'}&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166283 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974530&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:'cat_s:A'}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166284 INFO  (qtp30172609-34322) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974530&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:'cat_s:A'}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166285 INFO  (qtp15798085-34292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520680974530&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:'cat_s:A'}&ids=1&isShard=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166286 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:'cat_s:A'}&wt=smile&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 3166288 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974535&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:['cat_s:A']}&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166288 INFO  (qtp3829833-34308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974535&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:['cat_s:A']}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166289 INFO  (qtp30172609-34323) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974535&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:['cat_s:A']}&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166290 INFO  (qtp15798085-34292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520680974535&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:['cat_s:A']}&ids=1&isShard=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 3166291 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json={query:'*:*'}&json={filter:['where_s:NY']}&json={filter:['cat_s:A']}&wt=smile&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 3166293 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='*:*'&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974540&isShard=true&json.filter='where_s:NY'&json.filter='cat_s:A'&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166293 INFO  (qtp30172609-34323) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='*:*'&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974540&isShard=true&json.filter='where_s:NY'&json.filter='cat_s:A'&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166296 INFO  (qtp3829833-34304) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='*:*'&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974540&isShard=true&json.filter='where_s:NY'&json.filter='cat_s:A'&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166297 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='*:*'&shards.purpose=64&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&version=2&q=*:*&NOW=1520680974540&ids=1&isShard=true&json.filter='where_s:NY'&json.filter='cat_s:A'&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 3166298 INFO  (qtp30172609-34322) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json.query='*:*'&json.filter='where_s:NY'&json.filter='cat_s:A'&wt=smile&version=2}
 hits=1 status=0 QTime=5
   [junit4]   2> 3166301 INFO  (qtp15798085-34291) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='*:*'&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974548&isShard=true&json.filter='where_s:NY'&json.filter='cat_s:A'&json.filter=&json.filter=/*+*/&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 3166301 INFO  (qtp30172609-34324) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='*:*'&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52253/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974548&isShard=true&json.filter='where_s:NY'&json.filter='cat_s:A'&json.filter=&json.filter=/*+*/&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166301 INFO  (qtp3829833-34308) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='*:*'&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52250/solr/collection1&rows=10&version=2&q=*:*&NOW=1520680974548&isShard=true&json.filter='where_s:NY'&json.filter='cat_s:A'&json.filter=&json.filter=/*+*/&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 3166303 INFO  (qtp15798085-34292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='*:*'&shards.purpose=64&fq=where_s:NY&fq=cat_s:A&shard.url=127.0.0.1:52247/solr/collection1&version=2&q=*:*&NOW=1520680974548&ids=1&isShard=true&json.filter='where_s:NY'&json.filter='cat_s:A'&json.filter=&json.filter=/*+*/&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 3166303 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={shards=127.0.0.1:52247/solr/collection1,127.0.0.1:52250/solr/collection1,127.0.0.1:52253/solr/collection1&json.query='*:*'&json.filter='where_s:NY'&json.filter='cat_s:A'&json.filter=&json.filter=/*+*/&wt=smile&version=2}
 hits=1 status=0 QTime=3
   [junit4]   2> 3166306 INFO  (qtp15798085-34288) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={df=text&distrib=false&json.query='foo_s:NONE'&json.query='*:*'&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=where_s:NY&fq=cat_s:A&sha

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

RN  
(OverseerAutoScalingTriggerThread-72108952070914053-127.0.0.1:54376_solr-n_0000000000)
 [n:127.0.0.1:54376_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 109129 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x1002eb5b9500005, likely client has closed socket
   [junit4]   2> 109129 INFO  (jetty-closer-885-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1179fee{/solr,null,UNAVAILABLE}
   [junit4]   2> 109129 INFO  (jetty-closer-885-thread-1) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 109130 ERROR (SUITE-GraphTest-seed#[B0536CB0BB5E1436]-worker) 
[    ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so 
ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 109130 INFO  (SUITE-GraphTest-seed#[B0536CB0BB5E1436]-worker) 
[    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54372 54372
   [junit4]   2> 109131 INFO  (Thread-350) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:54372 54372
   [junit4]   2> 109132 WARN  (Thread-350) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        4       /solr/aliases.json
   [junit4]   2>        3       /solr/collections/collection1/terms/shard2
   [junit4]   2>        3       /solr/collections/collection1/terms/shard1
   [junit4]   2>        2       /solr/security.json
   [junit4]   2>        2       
/solr/collections/collection1/collectionprops.json
   [junit4]   2>        2       /solr/configs/conf
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        10      /solr/collections/collection1/state.json
   [junit4]   2>        4       /solr/clusterstate.json
   [junit4]   2>        4       /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        4       /solr/live_nodes
   [junit4]   2>        4       /solr/collections
   [junit4]   2> 
   [junit4]   2> 109132 INFO  (SUITE-GraphTest-seed#[B0536CB0BB5E1436]-worker) 
[    ] o.a.s.SolrTestCaseJ4 
------------------------------------------------------- Done waiting for 
tracked resources to be released
   [junit4]   2> Mar 10, 2018 11:26:51 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 5 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{_version_=PostingsFormat(name=Memory), from_s=PostingsFormat(name=Memory), 
multiDefault=FST50, predicate_s=BlockTreeOrds(blocksize=128), 
intDefault=PostingsFormat(name=Memory), to_s=BlockTreeOrds(blocksize=128), 
id=BlockTreeOrds(blocksize=128), timestamp=FST50}, 
docValues:{_version_=DocValuesFormat(name=Lucene70), 
multiDefault=DocValuesFormat(name=Asserting), 
from_s=DocValuesFormat(name=Lucene70), 
predicate_s=DocValuesFormat(name=Memory), to_s=DocValuesFormat(name=Memory), 
intDefault=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1107, 
maxMBSortInHeap=5.025493642465959, sim=RandomSimilarity(queryNorm=false): {}, 
locale=ar-TN, timezone=Etc/GMT+5
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=44358736,total=132169728
   [junit4]   2> NOTE: All tests run in this JVM: [SolrExampleStreamingTest, 
ReverseEvaluatorTest, TestBatchUpdate, NormalDistributionEvaluatorTest, 
FieldAnalysisResponseTest, UuidEvaluatorTest, TermsResponseTest, 
CloudSolrClientBuilderTest, ConcurrentUpdateSolrClientBuilderTest, 
ArcSineEvaluatorTest, AppendEvaluatorTest, JdbcTest, JettyWebappTest, 
SolrExampleXMLTest, TestUpdateRequestCodec, PowerEvaluatorTest, 
TestXMLEscaping, TestV1toV2ApiMapper, MultiplyEvaluatorTest, TestCoreAdmin, 
CeilingEvaluatorTest, TestJsonRecordReader, ModifiableSolrParamsTest, 
UsingSolrJRefGuideExamplesTest, GetByIdTest, CommonAdminParamsTest, 
FloorEvaluatorTest, TestSolrProperties, TestHash, TestCollectionAdminRequest, 
HttpSolrClientSSLAuthConPoolTest, SubtractEvaluatorTest, 
TestDelegationTokenRequest, SolrExampleEmbeddedTest, 
EmpiricalDistributionEvaluatorTest, ExclusiveOrEvaluatorTest, 
SolrExampleStreamingBinaryTest, LargeVolumeBinaryJettyTest, 
LargeVolumeEmbeddedTest, LargeVolumeJettyTest, MergeIndexesEmbeddedTest, 
SolrQueryTest, NamedListTest, TestLBHttpSolrClient, HttpSolrClientBuilderTest, 
HttpSolrClientConPoolTest, LBHttpSolrClientBuilderTest, 
TestCloudSolrClientConnections, GraphTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=GraphTest 
-Dtests.seed=B0536CB0BB5E1436 -Dtests.slow=true -Dtests.locale=ar-TN 
-Dtests.timezone=Etc/GMT+5 -Dtests.asserts=true -Dtests.file.encoding=Cp1252
   [junit4] ERROR   0.00s J0 | GraphTest (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-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data\tlog
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2\data
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1\collection1_shard2_replica_n2
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001\node1
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.graph.GraphTest_B0536CB0BB5E1436-001\tempDir-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([B0536CB0BB5E1436]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [99/146 (1!)] on J0 in 4.81s, 1 test, 1 error <<< 
FAILURES!

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

[repro] Revision: 9341be83701cf5d3675e9cde85da9ccb97044521

[repro] Ant options: "-Dargs=-client -XX:+UseConcMarkSweepGC"

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr\core
[repro]       TestSmileRequest
[repro]    lucene\core
[repro]       TestNIOFSDirectory
[repro]    solr\solrj
[repro]       GraphTest
[repro]    lucene\replicator
[repro]       IndexReplicationClientTest
[repro] ant compile-test

[...truncated 3331 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestSmileRequest" -Dtests.showOutput=onerror "-Dargs=-client 
-XX:+UseConcMarkSweepGC" -Dtests.seed=8A15200616B7C39C -Dtests.slow=true 
-Dtests.locale=it -Dtests.timezone=EAT -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

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

[...truncated 88 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestNIOFSDirectory" -Dtests.showOutput=onerror "-Dargs=-client 
-XX:+UseConcMarkSweepGC" -Dtests.seed=EF9143CB356036BF -Dtests.slow=true 
-Dtests.locale=cs -Dtests.timezone=Pacific/Gambier -Dtests.asserts=true 
-Dtests.file.encoding=Cp1252

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

[...truncated 447 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.GraphTest" -Dtests.showOutput=onerror "-Dargs=-client 
-XX:+UseConcMarkSweepGC" -Dtests.seed=B0536CB0BB5E1436 -Dtests.slow=true 
-Dtests.locale=ar-TN -Dtests.timezone=Etc/GMT+5 -Dtests.asserts=true 
-Dtests.file.encoding=Cp1252

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

[...truncated 179 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.IndexReplicationClientTest" -Dtests.showOutput=onerror 
"-Dargs=-client -XX:+UseConcMarkSweepGC" -Dtests.seed=5F6555E20FF46A36 
-Dtests.slow=true -Dtests.locale=es-EC -Dtests.timezone=Antarctica/Davis 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8

[...truncated 84 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.lucene.replicator.IndexReplicationClientTest
[repro]   0/5 failed: org.apache.lucene.store.TestNIOFSDirectory
[repro]   0/5 failed: org.apache.solr.client.solrj.io.graph.GraphTest
[repro]   0/5 failed: org.apache.solr.search.TestSmileRequest
[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

Reply via email to