[JENKINS] Lucene-Solr-6.x-Linux (64bit/jdk1.8.0_131) - Build # 3443 - Still Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3443/
Java: 64bit/jdk1.8.0_131 -XX:-UseCompressedOops -XX:+UseParallelGC

2 tests failed.
FAILED:  org.apache.solr.search.TestStressRecovery.testStressRecovery

Error Message:
Captured an uncaught exception in thread: Thread[id=16771, name=READER1, 
state=RUNNABLE, group=TGRP-TestStressRecovery]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=16771, name=READER1, state=RUNNABLE, 
group=TGRP-TestStressRecovery]
        at 
__randomizedtesting.SeedInfo.seed([E3152DCD8CF51543:592F4490131DAA4D]:0)
Caused by: java.lang.RuntimeException: java.lang.AssertionError
        at __randomizedtesting.SeedInfo.seed([E3152DCD8CF51543]:0)
        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:331)
Caused by: java.lang.AssertionError
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertTrue(Assert.java:54)
        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:318)


FAILED:  
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp

Error Message:
No such processor Template

Stack Trace:
org.apache.solr.common.SolrException: No such processor Template
        at 
__randomizedtesting.SeedInfo.seed([E3152DCD8CF51543:46A76D052ABBDCB7]:0)
        at 
org.apache.solr.update.processor.UpdateRequestProcessorChain.getReqProcessors(UpdateRequestProcessorChain.java:286)
        at 
org.apache.solr.update.processor.UpdateRequestProcessorChain.constructChain(UpdateRequestProcessorChain.java:235)
        at 
org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1413)
        at 
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp(UpdateRequestProcessorFactoryTest.java:53)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12439 lines...]
   [junit4] Suite: 
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_E3152DCD8CF51543-001/init-core-data-001
   [junit4]   2> 1165027 WARN  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 1165027 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1165029 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1165030 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1165030 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1165054 WARN  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1165055 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1165074 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1165195 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1165202 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1165230 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31612782
   [junit4]   2> 1165235 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31612782
   [junit4]   2> 1165235 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31612782
   [junit4]   2> 1165237 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1165255 WARN  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1165256 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1165265 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1165408 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1165414 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1165415 INFO  (coreLoadExecutor-4774-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@31612782
   [junit4]   2> 1165415 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1165415 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_E3152DCD8CF51543-001/init-core-data-001/]
   [junit4]   2> 1165417 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=17, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=40.328125, 
floorSegmentMB=0.6767578125, forceMergeDeletesPctAllowed=27.619450501617358, 
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1165489 WARN  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers no default request handler is registered 
(either '/select' or 'standard')
   [junit4]   2> 1165489 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1165489 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1165490 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=43, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=84.9423828125, 
floorSegmentMB=1.2275390625, forceMergeDeletesPctAllowed=11.249377307668459, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1165490 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6dcdce76[collection1] main]
   [junit4]   2> 1165491 WARN  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1165491 INFO  (coreLoadExecutor-4774-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1165492 INFO  
(searcherExecutor-4775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6dcdce76[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1165492 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1165494 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testConfiguration-seed#[E3152DCD8CF51543])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testConfiguration
   [junit4]   2> 1165495 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testConfiguration-seed#[E3152DCD8CF51543])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testConfiguration
   [junit4]   2> 1165497 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping-seed#[E3152DCD8CF51543])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testUpdateDistribChainSkipping
   [junit4]   2> 1165499 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping-seed#[E3152DCD8CF51543])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateDistribChainSkipping
   [junit4]   2> 1165501 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[E3152DCD8CF51543])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRequestTimeUrp
   [junit4]   2> 1165501 WARN  
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[E3152DCD8CF51543])
 [    ] o.a.s.c.PluginBag runtime library loading is not enabled, start Solr 
with -Denable.runtime.lib=true
   [junit4]   2> 1165502 INFO  
(TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[E3152DCD8CF51543])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testRequestTimeUrp
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=UpdateRequestProcessorFactoryTest -Dtests.method=testRequestTimeUrp 
-Dtests.seed=E3152DCD8CF51543 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=fr-CA -Dtests.timezone=Australia/Brisbane -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.01s J1 | 
UpdateRequestProcessorFactoryTest.testRequestTimeUrp <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: No such 
processor Template
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E3152DCD8CF51543:46A76D052ABBDCB7]:0)
   [junit4]    >        at 
org.apache.solr.update.processor.UpdateRequestProcessorChain.getReqProcessors(UpdateRequestProcessorChain.java:286)
   [junit4]    >        at 
org.apache.solr.update.processor.UpdateRequestProcessorChain.constructChain(UpdateRequestProcessorChain.java:235)
   [junit4]    >        at 
org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1413)
   [junit4]    >        at 
org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp(UpdateRequestProcessorFactoryTest.java:53)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1165504 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1165504 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=779330715
   [junit4]   2> 1165504 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1165504 INFO  (coreCloseExecutor-4779-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4636e92c
   [junit4]   2> 1165505 INFO  (coreCloseExecutor-4779-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1165506 INFO  
(SUITE-UpdateRequestProcessorFactoryTest-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_E3152DCD8CF51543-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1178, maxMBSortInHeap=5.43353882387296, 
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=fr-CA, 
timezone=Australia/Brisbane
   [junit4]   2> NOTE: Linux 4.4.0-75-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=12,threads=1,free=250451616,total=531628032
   [junit4]   2> NOTE: All tests run in this JVM: [TestNumericTerms32, 
TestSQLHandlerNonCloud, TestSolrCloudWithDelegationTokens, SearchHandlerTest, 
DirectSolrSpellCheckerTest, HdfsWriteToMultipleCollectionsTest, 
CollectionStateFormat2Test, SpellCheckCollatorTest, TestInPlaceUpdatesDistrib, 
DateMathParserTest, VersionInfoTest, CheckHdfsIndexTest, 
DistributedMLTComponentTest, TestFieldSortValues, 
TestRandomRequestDistribution, TestAuthorizationFramework, 
ShardRoutingCustomTest, MoveReplicaTest, TestConfigSets, SliceStateTest, 
ConnectionReuseTest, TestDeleteCollectionOnDownNodes, 
TestSha256AuthenticationProvider, CopyFieldTest, 
TestTrackingShardHandlerFactory, TestReRankQParserPlugin, 
TestSimpleTrackingShardHandler, TestSearchPerf, BasicAuthIntegrationTest, 
SolrJmxReporterTest, TestSubQueryTransformer, TestCSVResponseWriter, 
TestStressVersions, WordBreakSolrSpellCheckerTest, TestXmlQParser, 
DocumentBuilderTest, SolrMetricsIntegrationTest, HLLUtilTest, 
TestSolrDynamicMBean, DistanceFunctionTest, TestFieldResource, SampleTest, 
SchemaVersionSpecificBehaviorTest, TestClusterProperties, TestRTGBase, 
TestSortByMinMaxFunction, TestCloudDeleteByQuery, TestHalfAndHalfDocValues, 
CollectionsAPISolrJTest, TestDynamicFieldCollectionResource, 
TestFuzzyAnalyzedSuggestions, ClassificationUpdateProcessorTest, 
ShardRoutingTest, TestWordDelimiterFilterFactory, SpatialFilterTest, 
TestQueryUtils, HdfsBasicDistributedZkTest, TestSearcherReuse, 
TestTolerantUpdateProcessorCloud, SpatialRPTFieldTypeTest, 
TestFieldCacheVsDocValues, LoggingHandlerTest, TestCollectionAPIs, 
TestStressCloudBlindAtomicUpdates, TestRequestForwarding, TestLockTree, 
TestLuceneMatchVersion, TestRawTransformer, IndexSchemaTest, 
SharedFSAutoReplicaFailoverUtilsTest, TestDistributedStatsComponentCardinality, 
EnumFieldTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
BigEndianAscendingWordDeserializerTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestDynamicLoading, 
TestHashPartitioner, TestBlendedInfixSuggestions, 
DistributedTermsComponentTest, AtomicUpdatesTest, 
TestPartialUpdateDeduplication, ZkControllerTest, RemoteQueryErrorTest, 
TestMissingGroups, TestLRUCache, ConvertedLegacyTest, 
TestSolrQueryParserResource, TermVectorComponentTest, SolrInfoMBeanTest, 
TestScoreJoinQPScore, DistributedQueryComponentOptimizationTest, 
TestCursorMarkWithoutUniqueKey, CSVRequestHandlerTest, CdcrRequestHandlerTest, 
TestInitParams, OpenCloseCoreStressTest, SaslZkACLProviderTest, 
TestSystemCollAutoCreate, SmileWriterTest, TestTrie, EchoParamsTest, 
TestCloudPivotFacet, ZkCLITest, TestQueryWrapperFilter, 
DeleteInactiveReplicaTest, BasicFunctionalityTest, TestBulkSchemaAPI, 
BufferStoreTest, TestSolr4Spatial2, ExplicitHLLTest, TestBM25SimilarityFactory, 
RAMDirectoryFactoryTest, TestJsonFacets, SubstringBytesRefFilterTest, 
DistributedFacetPivotLargeTest, ZkSolrClientTest, ChaosMonkeySafeLeaderTest, 
BasicDistributedZk2Test, SyncSliceTest, OverseerTest, ClusterStateUpdateTest, 
AutoCommitTest, TestDistributedGrouping, TestFaceting, TestRecovery, 
TestStressReorder, HardAutoCommitTest, TestRangeQuery, StatsComponentTest, 
SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, 
TestFiltering, SimplePostToolTest, SolrRequestParserTest, 
TestFoldingMultitermQuery, DocValuesTest, CacheHeaderTest, 
TestSurroundQueryParser, StandardRequestHandlerTest, 
TestReversedWildcardFilterFactory, TestQueryTypes, TermsComponentTest, 
PathHierarchyTokenizerFactoryTest, TestSolrQueryParser, 
IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, ReturnFieldsTest, 
UpdateRequestProcessorFactoryTest]
   [junit4] Completed [427/711 (1!)] on J1 in 0.50s, 3 tests, 1 error <<< 
FAILURES!

[...truncated 79 lines...]
   [junit4] Suite: org.apache.solr.search.TestStressRecovery
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestStressRecovery_E3152DCD8CF51543-001/init-core-data-001
   [junit4]   2> 1176180 WARN  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 1176180 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1176181 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1176182 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1176182 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1176206 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1176218 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1176312 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1176324 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1176350 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2feb3b
   [junit4]   2> 1176355 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2feb3b
   [junit4]   2> 1176355 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2feb3b
   [junit4]   2> 1176357 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1176376 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1176388 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1176463 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1176468 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1176468 INFO  (coreLoadExecutor-4863-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@4d2feb3b
   [junit4]   2> 1176468 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1176468 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestStressRecovery_E3152DCD8CF51543-001/init-core-data-001/]
   [junit4]   2> 1176469 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d2feb3b
   [junit4]   2> 1176470 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.2073893619927837]
   [junit4]   2> 1176473 WARN  (coreLoadExecutor-4863-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> 1176505 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1176505 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1176519 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1176519 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1176520 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=18, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=77.3115234375, 
floorSegmentMB=1.80078125, forceMergeDeletesPctAllowed=1.778331397889411, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7904985982323034
   [junit4]   2> 1176520 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@226e2c19[collection1] main]
   [junit4]   2> 1176521 WARN  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1176521 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1176522 INFO  
(searcherExecutor-4864-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@226e2c19[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1176522 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 2147483647 transient cores
   [junit4]   2> 1176522 INFO  (coreLoadExecutor-4863-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1566555569675829248
   [junit4]   2> 1176525 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1176527 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testStressRecovery
   [junit4]   2> 1176527 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1176527 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4db1b326[collection1] realtime]
   [junit4]   2> 1176528 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 0
   [junit4]   2> 1176528 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1176528 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5cf4e7d9
   [junit4]   2> 1176528 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@600711be[collection1] main]
   [junit4]   2> 1176529 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1176530 INFO  (searcherExecutor-4864-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@600711be[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1176530 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1176534 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:48&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176536 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176536 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176536 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176537 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176536 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:73&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176537 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:94&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176537 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176537 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:59&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176537 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176537 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-1&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[17
 (-1)]} 0 2
   [junit4]   2> 1176537 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=2
   [junit4]   2> 1176537 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[17
 (-2)]} 0 0
   [junit4]   2> 1176537 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=80&wt=json} status=0 QTime=0
   [junit4]   2> 1176538 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-3&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0
 (-3)]} 0 3
   [junit4]   2> 1176538 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176538 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=58&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=31&wt=json} status=0 QTime=2
   [junit4]   2> 1176538 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0
   [junit4]   2> 1176538 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=3
   [junit4]   2> 1176538 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0
   [junit4]   2> 1176538 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176537 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1176538 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176538 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=108&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:103&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=32&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1176538 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176538 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=101&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176539 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176539 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176539 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:49&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176539 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:69&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=87&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=117&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=118&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=112&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=108&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:39&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176540 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=105&wt=json} status=0 QTime=0
   [junit4]   2> 1176538 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestStressRecovery_E3152DCD8CF51543-001/init-core-data-001/tlog/tlog.0000000000000000001
 refcount=1}}
   [junit4]   2> 1176538 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:45&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176541 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-4&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9
 (-4)]} 0 3
   [junit4]   2> 1176541 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1176538 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=53&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-6&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[65
 (-6)]} 0 0
   [junit4]   2> 1176541 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[17
 (-5)]} 0 3
   [junit4]   2> 1176541 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176541 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176541 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-7&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[65
 (-7)]} 0 0
   [junit4]   2> 1176541 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=30&wt=json} status=0 QTime=0
   [junit4]   2> 1176540 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:95&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176539 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176542 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176542 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176542 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176542 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=41&wt=json} status=0 QTime=0
   [junit4]   2> 1176539 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:119&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176542 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=61&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=33&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=39&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 WARN  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestStressRecovery_E3152DCD8CF51543-001/init-core-data-001/tlog/tlog.0000000000000000001
 refcount=2} active=true starting pos=83
   [junit4]   2> 1176541 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=89&wt=json} status=0 QTime=0
   [junit4]   2> 1176541 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 1
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=32&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=43&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=39&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=71&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=114&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=61&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 1176542 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:96&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176542 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1176543 INFO  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5cf4e7d9
   [junit4]   2> 1176544 INFO  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4f771edc[collection1] main]
   [junit4]   2> 1176544 INFO  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1176546 INFO  (searcherExecutor-4864-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4f771edc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1176546 INFO  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[9 (-4), 17 (-5), 65 
(-6), 65 (-7)]} 0 4
   [junit4]   2> 1176546 INFO  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
   [junit4]   2> 1176549 WARN  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=0 
deletes=4 deleteByQuery=0 errors=0 positionOfStart=83}
   [junit4]   2> 1176549 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1176549 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1176549 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 1176549 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=43&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=36&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=101&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176550 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:73&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176550 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=117&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:66&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176550 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1176550 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=115&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176550 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1176550 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=103&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=60&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:89&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176551 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=78&wt=json} status=0 QTime=0
   [junit4]   2> 1176549 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=105&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=50&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176551 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=117&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=104&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=102&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:118&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=119&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176551 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:47&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176550 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1176551 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176550 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=58&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176552 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176551 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:64&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176551 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176551 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176551 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 1176567 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176567 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=103&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=79&wt=json} status=0 QTime=0
   [junit4]   2> 1176553 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1176552 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:63&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176552 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=79&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1176552 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=43&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176568 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=114&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:35&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176568 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1176568 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176568 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1176568 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:25&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176568 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 15
   [junit4]   2> 1176569 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=115&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=62&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=116&wt=json} status=0 QTime=0
   [junit4]   2> 1176568 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=64&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=52&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:44&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176568 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=79&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=80&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1176569 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176569 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=39&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176569 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=68&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1176569 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1176569 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=69&wt=json} status=0 QTime=0
   [junit4]   2> 1176569 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 1176570 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1176570 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176570 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:62&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176570 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:30&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176570 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176570 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:114&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=40&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176571 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 1176571 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1176571 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=108&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-8&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[54
 (-8)]} 0 0
   [junit4]   2> 1176571 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:75&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176571 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:30&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176570 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1176571 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:29&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176572 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=60&wt=json} status=0 QTime=0
   [junit4]   2> 1176570 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (WRITER2) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1176572 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=80&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=61&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1176571 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176572 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=111&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:58&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176572 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=118&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:119&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1176572 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1176589 WARN  (recoveryExecutor-4866-thread-1) [    ] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestStressRecovery_E3152DCD8CF51543-001/init-core-data-001/tlog/tlog.0000000000000000003
 refcount=2} active=true starting pos=0
   [junit4]   2> 1176589 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:112&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176589 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=73&wt=json} status=0 QTime=0
   [junit4]   2> 1176589 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0
   [junit4]   2> 1176589 INFO  (WRITER1) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1176589 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=111&wt=json} status=0 QTime=0
   [junit4]   2> 1176589 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=103&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=99&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 1176572 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:76&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176590 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=78&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:29&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176590 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=79&wt=json} status=0 QTime=0
   [junit4]   2> 1176589 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1176590 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176589 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=94&wt=json} status=0 QTime=0
   [junit4]   2> 1176589 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=79&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:53&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176590 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:69&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176590 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=33&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:54&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176599 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=94&wt=json} status=0 QTime=8
   [junit4]   2> 1176599 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[54 
(9)]} 0 8
   [junit4]   2> 1176599 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=73&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=52&wt=json} status=0 QTime=0
   [junit4]   2> 1176599 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:72&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176590 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
   [junit4]   2> 1176590 INFO  (WRITER2) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1176600 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 9
   [junit4]   2> 1176600 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=99&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:77&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176600 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-10&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[54
 (-10)]} 0 1
   [junit4]   2> 1176600 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:57&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1176599 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=108&wt=json} status=0 QTime=0
   [junit4]   2> 1176599 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=8
   [junit4]   2> 1176599 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=32&wt=json} status=0 QTime=8
   [junit4]   2> 1176600 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=106&wt=json} status=0 QTime=0
   [junit4]   2> 1176599 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=8
   [junit4]   2> 1176601 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=0
   [junit4]   2> 1176601 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176601 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176601 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
   [junit4]   2> 1176601 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1176601 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=81&wt=json} status=0 QTime=0
   [junit4]   2> 1176601 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 1176600 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1176601 INFO  (READER2) [    ] o.a.s.c.S.Request

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

false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1183653 INFO  (recoveryExecutor-4866-thread-2) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5cf4e7d9
   [junit4]   2> 1183656 INFO  (recoveryExecutor-4866-thread-2) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4fb1c31[collection1] main]
   [junit4]   2> 1183658 INFO  (searcherExecutor-4864-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4fb1c31[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_47(6.6.0):c23/8:delGen=7)
 Uninverting(_48(6.6.0):C1) Uninverting(_49(6.6.0):C1) 
Uninverting(_4e(6.6.0):C2) Uninverting(_4k(6.6.0):C1) 
Uninverting(_4n(6.6.0):C1) Uninverting(_4o(6.6.0):C1) 
Uninverting(_4q(6.6.0):C1) Uninverting(_4v(6.6.0):C1) 
Uninverting(_4w(6.6.0):C1) Uninverting(_52(6.6.0):C2/1:delGen=1) 
Uninverting(_55(6.6.0):C1) Uninverting(_56(6.6.0):C2/1:delGen=1) 
Uninverting(_58(6.6.0):C2/1:delGen=1)))}
   [junit4]   2> mai 05, 2017 9:30:19 PM 
com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler
 uncaughtException
   [junit4]   2> AVERTISSEMENT: Uncaught exception in thread: 
Thread[READER1,5,TGRP-TestStressRecovery]
   [junit4]   2> java.lang.RuntimeException: java.lang.AssertionError
   [junit4]   2>        at 
__randomizedtesting.SeedInfo.seed([E3152DCD8CF51543]:0)
   [junit4]   2>        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:331)
   [junit4]   2> Caused by: java.lang.AssertionError
   [junit4]   2>        at org.junit.Assert.fail(Assert.java:92)
   [junit4]   2>        at org.junit.Assert.assertTrue(Assert.java:43)
   [junit4]   2>        at org.junit.Assert.assertTrue(Assert.java:54)
   [junit4]   2>        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:318)
   [junit4]   2> 
   [junit4]   2> 1183659 INFO  (recoveryExecutor-4866-thread-2) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1183659 INFO  (recoveryExecutor-4866-thread-2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[96 (1176)]} 0 6
   [junit4]   2> 1183659 INFO  (recoveryExecutor-4866-thread-2) [    ] 
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
   [junit4]   2> 1183661 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1183662 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-1177&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[96
 (-1177)]} 0 8
   [junit4]   2> 1183662 WARN  (recoveryExecutor-4866-thread-2) [    ] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=1 
deletes=0 deleteByQuery=0 errors=0 positionOfStart=104}
   [junit4]   2> 1183662 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@42397fa0[collection1] main]
   [junit4]   2> 1183663 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1183666 INFO  (searcherExecutor-4864-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@42397fa0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_47(6.6.0):c23/8:delGen=7)
 Uninverting(_48(6.6.0):C1) Uninverting(_49(6.6.0):C1) 
Uninverting(_4e(6.6.0):C2) Uninverting(_4k(6.6.0):C1) 
Uninverting(_4n(6.6.0):C1) Uninverting(_4o(6.6.0):C1) 
Uninverting(_4q(6.6.0):C1) Uninverting(_4v(6.6.0):C1) 
Uninverting(_4w(6.6.0):C1) Uninverting(_52(6.6.0):C2/1:delGen=1) 
Uninverting(_55(6.6.0):C1) Uninverting(_56(6.6.0):C2/1:delGen=1)))}
   [junit4]   2> 1183666 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 12
   [junit4]   2> 1183667 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[E3152DCD8CF51543]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testStressRecovery
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestStressRecovery 
-Dtests.method=testStressRecovery -Dtests.seed=E3152DCD8CF51543 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-LU 
-Dtests.timezone=Australia/Tasmania -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   7.14s J0 | TestStressRecovery.testStressRecovery <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=16771, name=READER1, state=RUNNABLE, 
group=TGRP-TestStressRecovery]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E3152DCD8CF51543:592F4490131DAA4D]:0)
   [junit4]    > Caused by: java.lang.RuntimeException: java.lang.AssertionError
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E3152DCD8CF51543]:0)
   [junit4]    >        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:331)
   [junit4]    > Caused by: java.lang.AssertionError
   [junit4]    >        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:318)
   [junit4]   2> 1183669 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1183669 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1773631917
   [junit4]   2> 1183670 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@c58f4c7
   [junit4]   2> 1183715 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1183717 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1183718 INFO  
(SUITE-TestStressRecovery-seed#[E3152DCD8CF51543]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62), 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=fr-LU, 
timezone=Australia/Tasmania
   [junit4]   2> NOTE: Linux 4.4.0-75-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=12,threads=1,free=190851448,total=535298048
   [junit4]   2> NOTE: All tests run in this JVM: [HdfsSyncSliceTest, 
TestScoreJoinQPNoScore, TestComponentsName, TestRandomFlRTGCloud, 
UtilsToolTest, TestOrdValues, TestJoin, TestGraphTermsQParserPlugin, 
TestStressLiveNodes, ClassificationUpdateProcessorIntegrationTest, 
TestLeaderElectionWithEmptyReplica, BaseCdcrDistributedZkTest, 
NotRequiredUniqueKeyTest, TestJettySolrRunner, TestValueSourceCache, 
TestFieldCollectionResource, TestRealTimeGet, WrapperMergePolicyFactoryTest, 
OverseerStatusTest, HighlighterConfigTest, OutputWriterTest, 
TestSolrConfigHandler, TestUseDocValuesAsStored2, SuggestComponentTest, 
DeleteStatusTest, TestRawResponseWriter, TestNoOpRegenerator, 
TestCloudJSONFacetJoinDomain, UpdateLogTest, BasicDistributedZkTest, 
ClusterStateTest, SolrCoreTest, TestBinaryResponseWriter, 
AlternateDirectoryTest, ScriptEngineTest, 
DistributedQueryElevationComponentTest, SignatureUpdateProcessorFactoryTest, 
JSONWriterTest, TestReplicaProperties, RequiredFieldsTest, 
DocExpirationUpdateProcessorFactoryTest, MBeansHandlerTest, BooleanFieldTest, 
HdfsUnloadDistributedZkTest, TestBlobHandler, HLLSerializationTest, 
FacetPivotSmallTest, ExitableDirectoryReaderTest, TestSolrDeletionPolicy2, 
LeaderElectionIntegrationTest, ResponseHeaderTest, TestCloudRecovery, 
LeaderElectionContextKeyTest, GraphQueryTest, CdcrUpdateLogTest, 
TestCoreAdminApis, TestStressLucene, TestRemoteStreaming, ResourceLoaderTest, 
TestUninvertingReader, TestFieldTypeResource, TestCryptoKeys, 
HdfsLockFactoryTest, ConfigureRecoveryStrategyTest, TestLazyCores, 
SynonymTokenizerTest, CursorPagingTest, XsltUpdateRequestHandlerTest, 
TestSolrCoreProperties, TestCustomStream, ExternalFileFieldSortTest, 
BlockJoinFacetDistribTest, CustomCollectionTest, SolrCLIZkUtilsTest, 
PeerSyncTest, TestCloudManagedSchema, DateFieldTest, TestIBSimilarityFactory, 
TestOmitPositions, RecoveryAfterSoftCommitTest, TestNamedUpdateProcessors, 
ReplicationFactorTest, TestSmileRequest, TestCustomDocTransformer, 
TestCrossCoreJoin, DistributedFacetExistsSmallTest, TestPerFieldSimilarity, 
CurrencyFieldOpenExchangeTest, TestTrieFacet, DisMaxRequestHandlerTest, 
TestClusterStateMutator, AnalysisAfterCoreReloadTest, QueryEqualityTest, 
CoreAdminCreateDiscoverTest, TestCollationFieldDocValues, HdfsRecoverLeaseTest, 
TestRTimerTree, CloudMLTQParserTest, MetricUtilsTest, TestIntervalFaceting, 
TestFieldCacheSortRandom, TestFunctionQuery, TestFieldCacheReopen, 
PKIAuthenticationIntegrationTest, TestChildDocTransformer, TestRandomFaceting, 
DistanceUnitsTest, TestSizeLimitedDistributedMap, TestPivotHelperCode, 
TestDistribIDF, DistributedFacetPivotSmallAdvancedTest, 
TestSolrCloudWithSecureImpersonation, ShufflingReplicaListTransformerTest, 
TestRestManager, CollectionsAPIDistributedZkTest, TestZkChroot, 
TestRandomDVFaceting, TestDistributedSearch, HighlighterTest, 
SolrIndexSplitterTest, SuggesterFSTTest, SuggesterTest, TestCSVLoader, 
PolyFieldTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, 
TestUpdate, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, 
SortByFunctionTest, TestSolrDeletionPolicy1, DebugComponentTest, 
IndexBasedSpellCheckerTest, LukeRequestHandlerTest, 
DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, 
FileBasedSpellCheckerTest, TestIndexingPerformance, MoreLikeThisHandlerTest, 
FastVectorHighlighterTest, RegexBoostProcessorTest, TestJmxIntegration, 
JSONWriterTest, UniqFieldsUpdateProcessorFactoryTest, 
TestPHPSerializedResponseWriter, TestQuerySenderNoQuery, TestStressRecovery]
   [junit4] Completed [454/711 (2!)] on J0 in 7.55s, 1 test, 1 error <<< 
FAILURES!

[...truncated 44309 lines...]

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