lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-6.x-Linux (64bit/jdk1.8.0_131) - Build # 3443 - Still Unstable!
Date Fri, 05 May 2017 11:55:30 GMT
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...]

Mime
View raw message