Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17144/
Java: 64bit/jdk1.8.0_92 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

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

Error Message:
ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper]

Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not 
released!!! [MockDirectoryWrapper]
        at __randomizedtesting.SeedInfo.seed([9E2E86F64EA5CFEB]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:256)
        at sun.reflect.GeneratedMethodAccessor16.invoke(Unknown Source)
        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:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
        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:367)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.search.TestStressReorder.testStressReorderVersions

Error Message:
Captured an uncaught exception in thread: Thread[id=1920, name=WRITER9, 
state=RUNNABLE, group=TGRP-TestStressReorder]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=1920, name=WRITER9, state=RUNNABLE, 
group=TGRP-TestStressReorder]
Caused by: java.lang.RuntimeException: java.lang.AssertionError: seqNo=29 vs 
maxSeqNo=29
        at __randomizedtesting.SeedInfo.seed([9E2E86F64EA5CFEB]:0)
        at 
org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:254)
Caused by: java.lang.AssertionError: seqNo=29 vs maxSeqNo=29
        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.getNextSequenceNumber(DocumentsWriterDeleteQueue.java:459)
        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:161)
        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:139)
        at 
org.apache.lucene.index.DocumentsWriterPerThread.finishDocument(DocumentsWriterPerThread.java:334)
        at 
org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:244)
        at 
org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
        at 
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
        at 
org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
        at 
org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
        at 
org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
        at 
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:115)
        at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:78)
        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:154)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2035)
        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:124)
        at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1119)
        at 
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1265)
        at 
org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:227)




Build Log:
[...truncated 10792 lines...]
   [junit4] Suite: org.apache.solr.search.TestStressReorder
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001
   [junit4]   2> 183398 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 183398 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 183398 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 183398 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 183398 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr
   [junit4]   2> 183399 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 183399 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 183422 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 183425 WARN  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
instead.
   [junit4]   2> 183426 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 183434 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 183441 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 183521 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 183522 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 183523 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 183525 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 183531 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 183531 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr
   [junit4]   2> 183531 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr'
   [junit4]   2> 183531 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 183531 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr
   [junit4]   2> 183531 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1351319905
   [junit4]   2> 183531 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr]
   [junit4]   2> 183532 WARN  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/lib
 to classpath: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/lib
   [junit4]   2> 183532 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 
600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,
   [junit4]   2> 183536 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&maxConnections=100000&maxConnectionsPerHost=100000
   [junit4]   2> 183536 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 183536 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 183536 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 183536 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 183537 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, 
config=solrconfig-tlog.xml, transient=false, schema=schema15.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
collection=collection1, 
dataDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001,
 shard=shard1}
   [junit4]   2> 183537 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 183537 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 183537 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr
   [junit4]   2> 183538 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 183538 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 183553 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 183555 WARN  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 183555 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 183562 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 183570 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 183681 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 183683 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 183683 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 183686 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 183695 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 183695 INFO  (coreLoadExecutor-967-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 183695 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 183695 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/]
   [junit4]   2> 183695 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@350e4515
   [junit4]   2> 183696 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001
   [junit4]   2> 183696 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/index/
   [junit4]   2> 183696 WARN  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 183697 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/index
   [junit4]   2> 183697 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=46, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=58.3154296875, 
floorSegmentMB=2.1845703125, forceMergeDeletesPctAllowed=13.600094983507873, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.6923138839120109
   [junit4]   2> 183697 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_1,generation=1}
   [junit4]   2> 183697 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 183700 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 183701 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 183701 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 183701 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 183701 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 183701 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 183702 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 183702 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 183702 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 183702 WARN  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 183703 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001/
   [junit4]   2> 183704 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 183706 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 183707 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 183708 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 183711 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
   [junit4]   2> 183711 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 183711 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 183711 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 183712 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 183712 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 183713 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=49, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=77.1044921875, 
floorSegmentMB=0.4912109375, forceMergeDeletesPctAllowed=3.793701802923364, 
segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4116257465620964
   [junit4]   2> 183713 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_1,generation=1}
   [junit4]   2> 183713 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 183714 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@73f8b263[collection1] main]
   [junit4]   2> 183714 WARN  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 183714 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 183714 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 183714 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 183714 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 183715 INFO  
(searcherExecutor-968-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@73f8b263[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 183715 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 183715 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 183715 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 183715 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1538915258236665856
   [junit4]   2> 183717 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with 
highest version 1538915258236665856
   [junit4]   2> 183717 INFO  (coreLoadExecutor-967-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 183719 INFO  
(SUITE-TestStressReorder-seed#[9E2E86F64EA5CFEB]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 183721 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testStressReorderVersions
   [junit4]   2> 183730 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 183732 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@56163577[collection1] realtime]
   [junit4]   2> 183732 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] 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 11
   [junit4]   2> 183733 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 183733 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_2,generation=2}
   [junit4]   2> 183733 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 183734 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@205b8948[collection1] main]
   [junit4]   2> 183735 INFO  (searcherExecutor-968-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@205b8948[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 183735 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 183736 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 183738 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[9E2E86F64EA5CFEB]) [    
] o.a.s.s.TestStressReorder VERBOSE: commitPercent 16 softCommitPercent 62 
deletePercent 7 deleteByQueryPercent 3 ndocs 23 nWriteThreads 18 
percentRealtimeQuery 75 operations 50000 nReadThreads 10
   [junit4]   2> 183742 INFO  (WRITER2) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 4 val= 2 version 1
   [junit4]   2> 183745 INFO  (WRITER4) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 15 val= 2 version 3
   [junit4]   2> 183745 INFO  (WRITER5) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 10 val= 2 version 4
   [junit4]   2> 183745 INFO  (WRITER7) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 7 val= 2 version 5
   [junit4]   2> 183742 INFO  (WRITER0) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 18 val= 2 version 2
   [junit4]   2> 183745 INFO  (WRITER12) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 18 val= 2 version 7
   [junit4]   2> 183745 INFO  (WRITER13) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 5 val= 2 version 6
   [junit4]   2> 183755 INFO  (WRITER11) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 2 val= 2 version 11
   [junit4]   2> 183755 INFO  (WRITER16) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 2 val= 2 version 10
   [junit4]   2> 183760 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 
QTime=3
   [junit4]   2> 183742 INFO  (WRITER1) [    ] o.a.s.s.TestStressReorder 
VERBOSE: hardCommit start
   [junit4]   2> 183763 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 183759 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183759 INFO  (WRITER8) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 2 val= 2 version 16
   [junit4]   2> 183765 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183759 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> 183759 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 183766 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183766 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183766 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183757 INFO  (WRITER15) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 2 val= 2 version 15
   [junit4]   2> 183757 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183766 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183756 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 183756 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 183756 INFO  (WRITER9) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 22 val= 2 version 14
   [junit4]   2> 183756 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183769 INFO  (READER1) [    ] 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> 183769 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183769 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183756 INFO  (WRITER10) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 2 val= 2 version 13
   [junit4]   2> 183756 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183756 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> 183755 INFO  (WRITER3) [    ] o.a.s.s.TestStressReorder 
VERBOSE: hardCommit start
   [junit4]   2> 183755 INFO  (WRITER14) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 2 val= 2 version 12
   [junit4]   2> 183774 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183755 INFO  (WRITER17) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 16 val= 2 version 9
   [junit4]   2> 183755 INFO  (WRITER6) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 11 val= 2 version 8
   [junit4]   2> 183754 INFO  (WRITER4) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[15 
(3)]} 0 8
   [junit4]   2> 183774 INFO  (WRITER4) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 19 val= 2 version 17
   [junit4]   2> 183777 INFO  (WRITER4) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-17&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[19
 (-17)]} 0 2
   [junit4]   2> 183777 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[10 
(4)]} 0 31
   [junit4]   2> 183777 INFO  (WRITER4) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 19 val= 2 version 17 DONE
   [junit4]   2> 183777 INFO  (WRITER4) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 17 val= 2 version 18
   [junit4]   2> 183781 INFO  (WRITER6) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[11 
(8)]} 0 6
   [junit4]   2> 183781 INFO  (WRITER17) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[16 
(9)]} 0 7
   [junit4]   2> 183781 INFO  (WRITER17) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 17 val= 2 version 21
   [junit4]   2> 183782 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=7
   [junit4]   2> 183782 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 183782 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183782 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183783 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183783 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> 183783 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183784 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 183784 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 183784 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 183784 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183785 INFO  (WRITER5) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 1 val= 2 version 19
   [junit4]   2> 183785 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=15
   [junit4]   2> 183785 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> 183785 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183785 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 183785 INFO  (READER0) [    ] 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> 183786 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183786 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183786 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 183786 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 183786 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[1 
(19)]} 0 0
   [junit4]   2> 183785 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=11
   [junit4]   2> 183786 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=19
   [junit4]   2> 183786 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183786 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 183787 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 183787 INFO  (WRITER5) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 7 val= 2 version 22
   [junit4]   2> 183787 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183787 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183787 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183788 INFO  (READER2) [    ] 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> 183788 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 183788 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183788 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 183788 INFO  (READER2) [    ] 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> 183788 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183788 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 183786 INFO  (WRITER6) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 18 val= 2 version 20
   [junit4]   2> 183786 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183789 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183789 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> 183789 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183789 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183789 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183790 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183790 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183790 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 183790 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 183790 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 183790 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 183790 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 183786 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183790 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> 183791 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183791 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183791 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183791 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183791 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183791 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183787 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=20
   [junit4]   2> 183791 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183791 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 183792 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183792 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183792 INFO  (READER8) [    ] 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> 183792 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> 183792 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183792 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 183787 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> 183792 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183793 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> 183792 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> 183791 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183793 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> 183793 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> 183791 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> 183793 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 183793 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=27
   [junit4]   2> 183793 INFO  (READER2) [    ] 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> 183793 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183793 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183793 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 183793 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> 183793 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> 183793 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> 183793 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 183794 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 183794 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 183794 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183794 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183794 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183794 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 183794 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 183794 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> 183794 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183794 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183794 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> 183794 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183795 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183795 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183795 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 183795 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> 183795 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 183795 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 183795 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183795 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> 183795 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183795 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183795 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183795 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 183796 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> 183796 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> 183796 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=29
   [junit4]   2> 183796 INFO  (READER2) [    ] 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> 183796 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 183796 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=30
   [junit4]   2> 183793 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183793 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183801 INFO  (WRITER1) [    ] o.a.s.c.SolrDeletionPolicy 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@111b3101 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@496f17ca),segFN=segments_3,generation=3}
   [junit4]   2> 183796 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 183803 INFO  (WRITER7) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[7 
(5)]} 0 57
   [junit4]   2> 183803 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183803 INFO  (WRITER7) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 10 val= 3 version 23
   [junit4]   2> 183793 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=27
   [junit4]   2> 183804 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=42
   [junit4]   2> 183804 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183804 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183804 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 183804 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 183804 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183804 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183805 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 183805 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183805 INFO  (READER5) [    ] 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> 183805 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 183793 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183793 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183805 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183805 INFO  (READER3) [    ] 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> 183806 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 183806 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 183806 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183806 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183806 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 183806 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 183806 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183804 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[4 
(1)]} 0 59
   [junit4]   2> 183806 INFO  (WRITER2) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 4 val= 3 version 24
   [junit4]   2> 183804 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 183807 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> 183807 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 183807 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183807 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> 183807 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 183807 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=4
   [junit4]   2> 183807 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183807 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 183807 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 183807 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 183807 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183807 INFO  (READER7) [    ] 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> 183808 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183807 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=11
   [junit4]   2> 183808 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183808 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183808 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183808 INFO  (READER9) [    ] 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> 183808 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=12
   [junit4]   2> 183808 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=12
   [junit4]   2> 183808 INFO  (READER9) [    ] 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> 183808 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183808 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183809 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183809 INFO  (READER2) [    ] 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> 183809 INFO  (READER4) [    ] 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> 183809 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183810 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183811 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 183811 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 183811 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 183811 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 183811 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=3
   [junit4]   2> 183811 INFO  (READER8) [    ] 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> 183811 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183811 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 183812 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183812 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183812 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 183812 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183812 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183812 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183812 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183812 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183811 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183810 INFO  (READER2) [    ] 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> 183810 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> 183812 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 183812 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183813 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183813 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> 183813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183813 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 183813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 183809 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> 183813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183813 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183813 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> 183813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183813 INFO  (READER8) [    ] 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> 183813 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> 183813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183813 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183813 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183814 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 183814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 183809 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=5
   [junit4]   2> 183814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 183814 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183814 INFO  (READER8) [    ] 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> 183814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 183814 INFO  (READER6) [    ] 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> 183814 INFO  (WRITER1) [    ] o.a.s.c.SolrDeletionPolicy 
newest commit generation = 3
   [junit4]   2> 183814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183814 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183814 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183808 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183815 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183808 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=12
   [junit4]   2> 183815 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183815 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 183816 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 183816 INFO  (READER6) [    ] 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> 183816 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183816 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> 183816 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 183816 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> 183816 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 183816 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 183816 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 183816 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 183816 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 183816 INFO  (READER9) [    ] 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> 183814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183814 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 183817 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183817 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> 183817 INFO  (READER2) [    ] 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> 183817 INFO  (READER2) [    ] 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> 183817 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{} 0 48
   [junit4]   2> 183818 ERROR (WRITER9) [    ] o.a.s.s.TestStressReorder 
   [junit4]   2> java.lang.AssertionError: seqNo=29 vs maxSeqNo=29
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.getNextSequenceNumber(DocumentsWriterDeleteQueue.java:459)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:161)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:139)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterPerThread.finishDocument(DocumentsWriterPerThread.java:334)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:244)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
   [junit4]   2>        at 
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
   [junit4]   2>        at 
org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
   [junit4]   2>        at 
org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
   [junit4]   2>        at 
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:115)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:78)
   [junit4]   2>        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
   [junit4]   2>        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:154)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2035)
   [junit4]   2>        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:124)
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1119)
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1265)
   [junit4]   2>        at 
org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:227)
   [junit4]   2> 183813 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=1
   [junit4]   2> 183813 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 183813 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=7
   [junit4]   2> 183812 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 183819 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{} 0 32
   [junit4]   2> 183817 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 183820 ERROR (WRITER5) [    ] o.a.s.s.TestStressReorder 
   [junit4]   2> java.lang.AssertionError: seqNo=30 vs maxSeqNo=29
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.getNextSequenceNumber(DocumentsWriterDeleteQueue.java:459)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:161)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:139)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterPerThread.finishDocument(DocumentsWriterPerThread.java:334)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:244)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
   [junit4]   2>        at 
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
   [junit4]   2>        at 
org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
   [junit4]   2>        at 
org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
   [junit4]   2>        at 
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:115)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:78)
   [junit4]   2>        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
   [junit4]   2>        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:154)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2035)
   [junit4]   2>        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:124)
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1119)
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1265)
   [junit4]   2>        at 
org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:227)
   [junit4]   2> 183816 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 183815 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  
webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 183821 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{} 0 62
   [junit4]   2> 183821 ERROR (WRITER0) [    ] o.a.s.s.TestStressReorder 
   [junit4]   2> java.lang.AssertionError: seqNo=31 vs maxSeqNo=29
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.getNextSequenceNumber(DocumentsWriterDeleteQueue.java:459)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:161)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterDeleteQueue.add(DocumentsWriterDeleteQueue.java:139)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterPerThread.finishDocument(DocumentsWriterPerThread.java:334)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:244)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
   [junit4]   2>        at 
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
   [junit4]   2>        at 
org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
   [junit4]   2>        at 
org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
   [junit4]   2>        at 
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:1

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

ava.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.RuntimeException: unclosed IndexOutput: 
_8.fdx
   [junit4]   2>        at 
org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:718)
   [junit4]   2>        at 
org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:653)
   [junit4]   2>        at 
org.apache.lucene.store.LockValidatingDirectoryWrapper.createOutput(LockValidatingDirectoryWrapper.java:44)
   [junit4]   2>        at 
org.apache.lucene.store.TrackingDirectoryWrapper.createOutput(TrackingDirectoryWrapper.java:43)
   [junit4]   2>        at 
org.apache.lucene.codecs.compressing.CompressingStoredFieldsWriter.<init>(CompressingStoredFieldsWriter.java:108)
   [junit4]   2>        at 
org.apache.lucene.codecs.compressing.CompressingStoredFieldsFormat.fieldsWriter(CompressingStoredFieldsFormat.java:128)
   [junit4]   2>        at 
org.apache.lucene.codecs.lucene50.Lucene50StoredFieldsFormat.fieldsWriter(Lucene50StoredFieldsFormat.java:183)
   [junit4]   2>        at 
org.apache.lucene.index.DefaultIndexingChain.initStoredFieldsWriter(DefaultIndexingChain.java:83)
   [junit4]   2>        at 
org.apache.lucene.index.DefaultIndexingChain.startStoredFields(DefaultIndexingChain.java:331)
   [junit4]   2>        at 
org.apache.lucene.index.DefaultIndexingChain.processDocument(DefaultIndexingChain.java:368)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(DocumentsWriterPerThread.java:231)
   [junit4]   2>        at 
org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:485)
   [junit4]   2>        at 
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1565)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:282)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:214)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:169)
   [junit4]   2>        at 
org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
   [junit4]   2>        at 
org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:48)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:939)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1094)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:720)
   [junit4]   2>        at 
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:492)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:139)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:115)
   [junit4]   2>        at 
org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:78)
   [junit4]   2>        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
   [junit4]   2>        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:154)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2035)
   [junit4]   2>        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:124)
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1119)
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1265)
   [junit4]   2>        at 
org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:227)
   [junit4]   2> 
   [junit4]   2> 183908 INFO  (coreCloseExecutor-973-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001;done=false>>]
   [junit4]   2> 183908 INFO  (coreCloseExecutor-973-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 183908 INFO  (coreCloseExecutor-973-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressReorder_9E2E86F64EA5CFEB-001/init-core-data-001
   [junit4]   2> ObjectTracker found 1 object(s) that were not released!!! 
[MockDirectoryWrapper]
   [junit4]   2> 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]   2>        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
   [junit4]   2>        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
   [junit4]   2>        at 
org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:63)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.initIndex(SolrCore.java:544)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.<init>(SolrCore.java:719)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.<init>(SolrCore.java:646)
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.create(CoreContainer.java:844)
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> NOTE: test params are: codec=Lucene62, 
sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=en-ZA, 
timezone=America/Tijuana
   [junit4]   2> NOTE: Linux 4.4.0-24-generic amd64/Oracle Corporation 1.8.0_92 
(64-bit)/cpus=12,threads=1,free=465469008,total=508952576
   [junit4]   2> NOTE: All tests run in this JVM: [TestCharFilters, 
SharedFSAutoReplicaFailoverUtilsTest, TestFieldCache, 
AnalyticsMergeStrategyTest, RestartWhileUpdatingTest, TestCSVResponseWriter, 
ChangedSchemaMergeTest, TestDefaultStatsCache, TestJmxMonitoredMap, 
CurrencyFieldXmlFileTest, MergeStrategyTest, TestLeaderElectionZkExpiry, 
TestFilteredDocIdSet, TestDistributedMissingSort, TestClusterStateMutator, 
HardAutoCommitTest, HdfsDirectoryTest, ConnectionManagerTest, 
TestCollationField, TestInitParams, TestSchemaManager, TestExactStatsCache, 
DistributedFacetPivotSmallAdvancedTest, TestFieldCacheSort, 
PrimitiveFieldTypeTest, ClusterStateTest, TestPostingsSolrHighlighter, 
ConjunctionSolrSpellCheckerTest, SuggesterWFSTTest, TestNamedUpdateProcessors, 
HdfsChaosMonkeySafeLeaderTest, TestFileDictionaryLookup, FullHLLTest, 
DeleteInactiveReplicaTest, TestWriterPerf, TestIndexSearcher, TestStressReorder]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestStressReorder 
-Dtests.seed=9E2E86F64EA5CFEB -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=en-ZA -Dtests.timezone=America/Tijuana -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J2 | TestStressReorder (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 
object(s) that were not released!!! [MockDirectoryWrapper]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([9E2E86F64EA5CFEB]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:256)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [81/618 (1!)] on J2 in 0.53s, 1 test, 1 failure, 1 error 
<<< FAILURES!

[...truncated 1749 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:740: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:684: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:233: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/common-build.xml:530: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1427: 
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:984: 
There were test failures: 618 suites (10 ignored), 2590 tests, 1 suite-level 
error, 1 error, 117 ignored (72 assumptions) [seed: 9E2E86F64EA5CFEB]

Total time: 59 minutes 30 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to