Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/5225/
Java: 32bit/jdk1.8.0_60 -client -XX:+UseSerialGC

1 tests failed.
FAILED:  org.apache.solr.search.TestStressUserVersions.testStressReorderVersions

Error Message:
Captured an uncaught exception in thread: Thread[id=21986, name=READER3, 
state=RUNNABLE, group=TGRP-TestStressUserVersions]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=21986, name=READER3, state=RUNNABLE, 
group=TGRP-TestStressUserVersions]
        at 
__randomizedtesting.SeedInfo.seed([E91ADE7A45E625A0:F5DCE76C4F2DA12D]:0)
Caused by: java.lang.RuntimeException: java.lang.AssertionError
        at __randomizedtesting.SeedInfo.seed([E91ADE7A45E625A0]:0)
        at 
org.apache.solr.search.TestStressUserVersions$2.run(TestStressUserVersions.java:302)
Caused by: java.lang.AssertionError
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertTrue(Assert.java:54)
        at 
org.apache.solr.search.TestStressUserVersions$2.run(TestStressUserVersions.java:293)




Build Log:
[...truncated 10982 lines...]
   [junit4] Suite: org.apache.solr.search.TestStressUserVersions
   [junit4]   2> Creating dataDir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001
   [junit4]   2> 2920998 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 2920999 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2921000 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 2921000 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 2921000 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 2921028 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2921032 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2921039 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-externalversionconstraint.xml
   [junit4]   2> 2921040 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml
   [junit4]   2> 2921057 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 2921147 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2921149 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2921151 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2921154 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2921164 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2921164 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr
   [junit4]   2> 2921164 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\'
   [junit4]   2> 2921182 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 25103217
   [junit4]   2> 2921182 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\]
   [junit4]   2> 2921182 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.CoreContainer loading shared library: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\lib
   [junit4]   2> 2921182 WARN  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\lib).
   [junit4]   2> 2921191 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-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,useRetries : false,
   [junit4]   2> 2921192 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2921193 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2921193 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2921193 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 2921193 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2921195 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig-externalversionconstraint.xml, transient=false, 
schema=schema15.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, instanceDir=collection1, 
collection=collection1, 
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\,
 
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001,
 shard=shard1}
   [junit4]   2> 2921195 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 2921196 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 2921196 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 2921217 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2921221 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2921237 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-externalversionconstraint.xml
   [junit4]   2> 2921237 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml
   [junit4]   2> 2921247 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2921323 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2921324 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2921325 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2921327 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2921335 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf'
   [junit4]   2> 2921335 INFO  (coreLoadExecutor-9392-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 2921335 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2921335 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\],
 dataDir=[null]
   [junit4]   2> 2921335 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c49839
   [junit4]   2> 2921336 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\
   [junit4]   2> 2921336 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\index/
   [junit4]   2> 2921337 WARN  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\index'
 doesn't exist. Creating new index...
   [junit4]   2> 2921337 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\index
   [junit4]   2> 2921337 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=11.5537109375, 
floorSegmentMB=1.24609375, forceMergeDeletesPctAllowed=3.041825737374052, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 2921338 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@164d236 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10d1061),segFN=segments_1,generation=1}
   [junit4]   2> 2921338 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2921339 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\
   [junit4]   2> 2921343 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "external-version-constraint" (default)
   [junit4]   2> 2921344 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"external-version-constraint" (default)
   [junit4]   2> 2921345 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "external-version-float"
   [junit4]   2> 2921345 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"external-version-float"
   [junit4]   2> 2921345 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "external-version-failhard"
   [junit4]   2> 2921345 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"external-version-failhard"
   [junit4]   2> 2921349 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2921350 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2921352 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2921353 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2921358 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 2921359 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2921360 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2921360 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2921361 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 2921361 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 2921362 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=45, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.23580244735152445]
   [junit4]   2> 2921362 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@164d236 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10d1061),segFN=segments_1,generation=1}
   [junit4]   2> 2921362 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2921363 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1bf1bd5[collection1] 
main
   [junit4]   2> 2921363 WARN  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 2921363 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 2921364 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 2921364 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2921364 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2921365 INFO  
(searcherExecutor-9393-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1bf1bd5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2921366 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 2921366 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 2921366 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 2921366 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1511264495264071680
   [junit4]   2> 2921368 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with 
highest version 1511264495264071680
   [junit4]   2> 2921368 INFO  (coreLoadExecutor-9392-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2921369 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2921372 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testStressReorderVersions
   [junit4]   2> 2921372 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2921372 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.s.SolrIndexSearcher Opening Searcher@6f476d[collection1] realtime
   [junit4]   2> 2921372 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.c.S.Request [collection1] webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}
 status=0 QTime=0 
   [junit4]   2> 2921373 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2921373 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@164d236 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10d1061),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@164d236 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10d1061),segFN=segments_2,generation=2}
   [junit4]   2> 2921374 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 2921374 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.s.SolrIndexSearcher Opening Searcher@768ac9[collection1] main
   [junit4]   2> 2921374 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2921375 INFO  (searcherExecutor-9393-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@768ac9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2921376 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={} status=0 
QTime=3 
   [junit4]   2> 2921379 INFO  
(TEST-TestStressUserVersions.testStressReorderVersions-seed#[E91ADE7A45E625A0]) 
[    ] o.a.s.SolrTestCaseJ4 VERBOSE: commitPercent 8 softCommitPercent 60 
deletePercent 16 deleteByQueryPercent 4 ndocs 181 nWriteThreads 22 
percentRealtimeQuery 75 operations 10000 nReadThreads 14
   [junit4]   2> 2921382 INFO  (WRITER2) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 0 val= 2 version 2
   [junit4]   2> 2921382 INFO  (WRITER1) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 31 val= 2 version 3
   [junit4]   2> 2921382 INFO  (WRITER3) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
hardCommit start
   [junit4]   2> 2921383 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2921382 INFO  (WRITER0) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 0 val= 2 version 1
   [junit4]   2> 2921382 INFO  (WRITER4) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 0 val= 2 version 4
   [junit4]   2> 2921401 INFO  (WRITER7) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 0 val= 2 version 5
   [junit4]   2> 2921401 INFO  (WRITER11) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit start
   [junit4]   2> 2921402 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:109&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921402 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=68&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921403 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921403 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:109&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921403 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=170&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921403 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=115&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921403 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=179&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921403 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=30&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921403 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:108&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921404 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:109&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921402 INFO  (WRITER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={del_version=2&versions=true&wt=json&indent=true} 
status=0 QTime=19 
   [junit4]   2> 2921404 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:75&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921404 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921402 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0 
   [junit4]   2> 2921404 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921405 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:128&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921405 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=99&wt=json} status=0 QTime=0 
   [junit4]   2> 2921405 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921406 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=111&wt=json} status=0 QTime=0 
   [junit4]   2> 2921406 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921404 INFO  (READER10) [    ] 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> 2921406 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:66&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921407 INFO  (WRITER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={del_version=1&versions=true&wt=json&indent=true} 
status=0 QTime=20 
   [junit4]   2> 2921407 INFO  (READER9) [    ] 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> 2921407 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=135&wt=json} status=0 QTime=0 
   [junit4]   2> 2921407 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0 
   [junit4]   2> 2921407 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> 2921408 INFO  (WRITER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=6 
   [junit4]   2> 2921408 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0 
   [junit4]   2> 2921408 INFO  (WRITER7) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
hardCommit start
   [junit4]   2> 2921408 INFO  (WRITER18) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 2 version 19
   [junit4]   2> 2921408 INFO  (WRITER20) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 2 version 18
   [junit4]   2> 2921408 INFO  (WRITER5) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 14 val= 2 version 12
   [junit4]   2> 2921409 INFO  (WRITER13) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 2 version 11
   [junit4]   2> 2921409 INFO  (WRITER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={del_version=12&versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921409 INFO  (WRITER5) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 14 val= 2 version 12 DONE
   [junit4]   2> 2921409 INFO  (WRITER18) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921410 INFO  (WRITER20) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921410 INFO  (WRITER9) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 98 val= 2 version 7
   [junit4]   2> 2921410 INFO  (WRITER3) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@164d236 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10d1061),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@164d236 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10d1061),segFN=segments_3,generation=3}
   [junit4]   2> 2921404 INFO  (WRITER2) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 0 val= 2 version 2 DONE
   [junit4]   2> 2921410 INFO  (WRITER3) [    ] o.a.s.c.SolrCore newest commit 
generation = 3
   [junit4]   2> 2921410 INFO  (WRITER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={del_version=7&versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921404 INFO  (WRITER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=20 
   [junit4]   2> 2921411 INFO  (WRITER1) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 118 val= 2 version 25
   [junit4]   2> 2921403 INFO  (WRITER11) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 2921403 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=139&wt=json} status=0 
QTime=1 
   [junit4]   2> 2921403 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 
QTime=2 
   [junit4]   2> 2921413 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=31&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921411 INFO  (WRITER9) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 98 val= 2 version 7 DONE
   [junit4]   2> 2921413 INFO  (WRITER9) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 178 val= 2 version 26
   [junit4]   2> 2921414 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:97&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921410 INFO  (WRITER2) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 90 val= 2 version 24
   [junit4]   2> 2921414 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921410 INFO  (WRITER20) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 3 version 23
   [junit4]   2> 2921410 INFO  (WRITER8) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 2 version 6
   [junit4]   2> 2921410 INFO  (WRITER10) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 24 val= 2 version 8
   [junit4]   2> 2921414 INFO  (WRITER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=0 
   [junit4]   2> 2921414 INFO  (WRITER8) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 20 val= 2 version 27
   [junit4]   2> 2921410 INFO  (WRITER6) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 2 version 9
   [junit4]   2> 2921410 INFO  (WRITER12) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 2 version 10
   [junit4]   2> 2921415 INFO  (WRITER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=0 
   [junit4]   2> 2921415 INFO  (WRITER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921410 INFO  (WRITER18) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 3 version 22
   [junit4]   2> 2921409 INFO  (WRITER5) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 3 version 21
   [junit4]   2> 2921409 INFO  (WRITER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921416 INFO  (WRITER13) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 9 val= 2 version 30
   [junit4]   2> 2921408 INFO  (WRITER14) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 79 val= 2 version 13
   [junit4]   2> 2921408 INFO  (WRITER15) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 2 version 14
   [junit4]   2> 2921408 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 17 val= 2 version 15
   [junit4]   2> 2921408 INFO  (WRITER17) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 2 version 16
   [junit4]   2> 2921408 INFO  (WRITER19) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 109 val= 2 version 17
   [junit4]   2> 2921408 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:129&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921417 INFO  (WRITER19) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=17&versions=true&wt=json&indent=true} status=0 QTime=0 
   [junit4]   2> 2921417 INFO  (WRITER19) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 109 val= 2 version 17 DONE
   [junit4]   2> 2921417 INFO  (WRITER17) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921408 INFO  (WRITER21) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 109 val= 2 version 20
   [junit4]   2> 2921408 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921408 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921408 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921418 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:157&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921407 INFO  (WRITER0) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 0 val= 2 version 1 DONE
   [junit4]   2> 2921419 INFO  (WRITER0) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
hardCommit start
   [junit4]   2> 2921419 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:51&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921407 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921419 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> 2921419 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:55&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921419 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:136&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921419 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0 
   [junit4]   2> 2921407 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921420 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=129&wt=json} status=0 QTime=0 
   [junit4]   2> 2921407 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0 
   [junit4]   2> 2921407 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=1 
   [junit4]   2> 2921421 INFO  (READER4) [    ] 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> 2921421 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 QTime=0 
   [junit4]   2> 2921406 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=129&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921406 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=31&wt=json} status=0 QTime=0 
   [junit4]   2> 2921421 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=48&wt=json} status=0 QTime=0 
   [junit4]   2> 2921422 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:52&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921406 INFO  (WRITER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=19 
   [junit4]   2> 2921422 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> 2921405 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=147&wt=json} status=0 QTime=0 
   [junit4]   2> 2921422 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:160&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921423 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:118&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921405 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921423 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=135&wt=json} status=0 QTime=0 
   [junit4]   2> 2921423 INFO  (READER11) [    ] 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> 2921423 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=79&wt=json} status=0 QTime=0 
   [junit4]   2> 2921422 INFO  (WRITER4) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 55 val= 2 version 33
   [junit4]   2> 2921422 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:159&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921421 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=50&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921421 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0 
   [junit4]   2> 2921425 INFO  (READER10) [    ] 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> 2921425 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:50&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921420 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0 
   [junit4]   2> 2921420 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=160&wt=json} status=0 QTime=0 
   [junit4]   2> 2921420 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> 2921426 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:55&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921419 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:125&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921426 INFO  (READER9) [    ] 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> 2921418 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0 
   [junit4]   2> 2921418 INFO  (WRITER17) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 3 version 32
   [junit4]   2> 2921426 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=148&wt=json} status=0 QTime=0 
   [junit4]   2> 2921417 INFO  (WRITER19) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 109 val= 3 version 31
   [junit4]   2> 2921417 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> 2921415 INFO  (WRITER12) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 122 val= 2 version 29
   [junit4]   2> 2921427 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=58&wt=json} status=0 QTime=0 
   [junit4]   2> 2921415 INFO  (WRITER6) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 63 val= 2 version 28
   [junit4]   2> 2921414 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@6cde9a[collection1] main
   [junit4]   2> 2921414 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:94&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921428 INFO  (WRITER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=0 
   [junit4]   2> 2921428 INFO  (WRITER6) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 55 val= 2 version 34
   [junit4]   2> 2921428 INFO  (WRITER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=1 
   [junit4]   2> 2921429 INFO  (WRITER12) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 4 val= 2 version 35
   [junit4]   2> 2921429 INFO  (searcherExecutor-9393-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6cde9a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c6/1:delGen=1)))}
   [junit4]   2> 2921413 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=141&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921429 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=53&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921429 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921430 INFO  (WRITER16) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=15&versions=true&wt=json&indent=true} status=0 QTime=13 
   [junit4]   2> 2921430 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 17 val= 2 version 15 DONE
   [junit4]   2> 2921430 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 172 val= 2 version 36
   [junit4]   2> 2921430 INFO  (WRITER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=14 
   [junit4]   2> 2921430 INFO  (WRITER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=15 
   [junit4]   2> 2921431 INFO  (WRITER5) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 4 version 37
   [junit4]   2> 2921431 INFO  (WRITER17) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=4 
   [junit4]   2> 2921431 INFO  (WRITER17) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 9 val= 2 version 39
   [junit4]   2> 2921429 INFO  (WRITER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=4 
   [junit4]   2> 2921432 INFO  (WRITER4) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 9 val= 2 version 40
   [junit4]   2> 2921432 INFO  (WRITER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=8&versions=true&wt=json&indent=true} status=0 QTime=17 
   [junit4]   2> 2921432 INFO  (WRITER10) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 24 val= 2 version 8 DONE
   [junit4]   2> 2921428 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921428 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 2921433 INFO  (WRITER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={} status=0 QTime=50 
   [junit4]   2> 2921427 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0 
   [junit4]   2> 2921434 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0 
   [junit4]   2> 2921427 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0 
   [junit4]   2> 2921434 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0 
   [junit4]   2> 2921435 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:102&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921435 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:129&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921436 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=123&wt=json} status=0 QTime=0 
   [junit4]   2> 2921436 INFO  (READER5) [    ] 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> 2921436 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:47&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921437 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=0 
   [junit4]   2> 2921437 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0 
   [junit4]   2> 2921437 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=142&wt=json} status=0 QTime=0 
   [junit4]   2> 2921437 INFO  (READER5) [    ] 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> 2921426 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0 
   [junit4]   2> 2921438 INFO  (READER5) [    ] 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> 2921426 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0 
   [junit4]   2> 2921426 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=149&wt=json} status=0 QTime=0 
   [junit4]   2> 2921425 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=129&wt=json} status=0 QTime=0 
   [junit4]   2> 2921425 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=144&wt=json} status=0 QTime=0 
   [junit4]   2> 2921425 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921439 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:70&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921424 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0 
   [junit4]   2> 2921424 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=153&wt=json} status=0 QTime=0 
   [junit4]   2> 2921424 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:30&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921423 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=179&wt=json} status=0 QTime=0 
   [junit4]   2> 2921441 INFO  (READER7) [    ] 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> 2921440 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=43&wt=json} status=0 QTime=0 
   [junit4]   2> 2921441 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921440 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0 
   [junit4]   2> 2921440 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0 
   [junit4]   2> 2921439 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921439 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=147&wt=json} status=0 QTime=0 
   [junit4]   2> 2921442 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0 
   [junit4]   2> 2921439 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=33&wt=json} status=0 QTime=0 
   [junit4]   2> 2921438 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=170&wt=json} status=0 QTime=0 
   [junit4]   2> 2921438 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0 
   [junit4]   2> 2921438 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> 2921443 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:155&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921443 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0 
   [junit4]   2> 2921438 INFO  (WRITER11) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@a44600[collection1] main
   [junit4]   2> 2921443 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=157&wt=json} status=0 QTime=0 
   [junit4]   2> 2921444 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:89&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921436 INFO  (WRITER3) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
hardCommit end
   [junit4]   2> 2921444 INFO  (WRITER3) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 9 val= 2 version 42
   [junit4]   2> 2921444 INFO  (searcherExecutor-9393-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a44600[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c6/3:delGen=1)
 Uninverting(_2(6.0.0):c20/5:delGen=1)))}
   [junit4]   2> 2921434 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2921445 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=91&wt=json} status=0 QTime=1 
   [junit4]   2> 2921445 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=115&wt=json} status=0 QTime=0 
   [junit4]   2> 2921434 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:78&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921446 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=107&wt=json} status=0 QTime=2 
   [junit4]   2> 2921447 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=1 
   [junit4]   2> 2921447 INFO  (WRITER19) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=31&versions=true&wt=json&indent=true} status=0 QTime=20 
   [junit4]   2> 2921448 INFO  (WRITER19) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 109 val= 3 version 31 DONE
   [junit4]   2> 2921448 INFO  (WRITER19) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 9 val= 2 version 43
   [junit4]   2> 2921448 INFO  (WRITER7) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@164d236 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10d1061),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@164d236 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10d1061),segFN=segments_4,generation=4}
   [junit4]   2> 2921449 INFO  (WRITER7) [    ] o.a.s.c.SolrCore newest commit 
generation = 4
   [junit4]   2> 2921433 INFO  (READER12) [    ] 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> 2921450 INFO  (WRITER21) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=20&versions=true&wt=json&indent=true} status=0 QTime=31 
   [junit4]   2> 2921433 INFO  (WRITER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=19 
   [junit4]   2> 2921433 INFO  (WRITER10) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 9 val= 2 version 41
   [junit4]   2> 2921433 INFO  (WRITER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=19 
   [junit4]   2> 2921432 INFO  (WRITER20) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=18 
   [junit4]   2> 2921432 INFO  (WRITER17) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=1 
   [junit4]   2> 2921452 INFO  (WRITER9) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
hardCommit start
   [junit4]   2> 2921432 INFO  (WRITER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=0 
   [junit4]   2> 2921453 INFO  (WRITER4) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 87 val= 2 version 47
   [junit4]   2> 2921453 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@e6fa19[collection1] main
   [junit4]   2> 2921432 INFO  (WRITER15) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=14 
   [junit4]   2> 2921431 INFO  (WRITER8) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 135 val= 2 version 38
   [junit4]   2> 2921431 INFO  (WRITER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=0 
   [junit4]   2> 2921454 INFO  (WRITER5) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 14 val= 5 version 49
   [junit4]   2> 2921454 INFO  (WRITER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={del_version=47&versions=true&wt=json&indent=true} 
status=0 QTime=1 
   [junit4]   2> 2921454 INFO  (WRITER4) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 87 val= 2 version 47 DONE
   [junit4]   2> 2921430 INFO  (WRITER16) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921454 INFO  (searcherExecutor-9393-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e6fa19[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c6/3:delGen=2)
 Uninverting(_2(6.0.0):c20/7:delGen=2) Uninverting(_3(6.0.0):c1) 
Uninverting(_4(6.0.0):c1)))}
   [junit4]   2> 2921455 INFO  (WRITER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=10 
   [junit4]   2> 2921455 INFO  (WRITER3) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit start
   [junit4]   2> 2921430 INFO  (WRITER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=14 
   [junit4]   2> 2921455 INFO  (WRITER13) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 59 val= 2 version 52
   [junit4]   2> 2921455 INFO  (WRITER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=5 
   [junit4]   2> 2921456 INFO  (WRITER10) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 176 val= 2 version 53
   [junit4]   2> 2921430 INFO  (WRITER14) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=13 
   [junit4]   2> 2921457 INFO  (WRITER19) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=9 
   [junit4]   2> 2921457 INFO  (WRITER19) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 75 val= 2 version 55
   [junit4]   2> 2921457 INFO  (WRITER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921430 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921430 INFO  (WRITER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=1 
   [junit4]   2> 2921457 INFO  (WRITER6) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 133 val= 2 version 57
   [junit4]   2> 2921458 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921458 INFO  (WRITER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={del_version=57&versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921429 INFO  (WRITER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921458 INFO  (WRITER6) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 133 val= 2 version 57 DONE
   [junit4]   2> 2921458 INFO  (WRITER6) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 133 val= 3 version 58
   [junit4]   2> 2921458 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=125&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921457 INFO  (WRITER19) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921457 INFO  (WRITER10) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 75 val= 2 version 56
   [junit4]   2> 2921457 INFO  (WRITER14) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 75 val= 2 version 54
   [junit4]   2> 2921460 INFO  (WRITER14) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=54&versions=true&wt=json&indent=true} status=0 QTime=0 
   [junit4]   2> 2921456 INFO  (WRITER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={del_version=49&versions=true&wt=json&indent=true} 
status=0 QTime=3 
   [junit4]   2> 2921456 INFO  (WRITER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921455 INFO  (WRITER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=2 
   [junit4]   2> 2921455 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 2921455 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 60 val= 2 version 51
   [junit4]   2> 2921455 INFO  (WRITER4) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 87 val= 3 version 50
   [junit4]   2> 2921455 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 2921461 INFO  (WRITER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={} status=0 QTime=53 
   [junit4]   2> 2921463 INFO  (WRITER7) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
hardCommit end
   [junit4]   2> 2921463 INFO  (WRITER7) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 135 val= 3 version 62
   [junit4]   2> 2921454 INFO  (WRITER15) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 85 val= 2 version 48
   [junit4]   2> 2921453 INFO  (WRITER20) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 4 version 46
   [junit4]   2> 2921453 INFO  (WRITER17) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 178 val= 3 version 45
   [junit4]   2> 2921452 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921466 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=107&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921467 INFO  (READER12) [    ] 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> 2921452 INFO  (WRITER21) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 109 val= 2 version 20 DONE
   [junit4]   2> 2921467 INFO  (WRITER21) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 4 version 63
   [junit4]   2> 2921451 INFO  (WRITER2) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 17 val= 3 version 44
   [junit4]   2> 2921450 INFO  (WRITER18) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=33 
   [junit4]   2> 2921450 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=3 
   [junit4]   2> 2921450 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=2 
   [junit4]   2> 2921470 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921470 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@173d197[collection1] main
   [junit4]   2> 2921470 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:25&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921446 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:27&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921445 INFO  (WRITER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=33 
   [junit4]   2> 2921471 INFO  (WRITER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=2 
   [junit4]   2> 2921471 INFO  (WRITER2) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit start
   [junit4]   2> 2921471 INFO  (WRITER17) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=6 
   [junit4]   2> 2921471 INFO  (WRITER20) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=6 
   [junit4]   2> 2921443 INFO  (WRITER11) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 2921472 INFO  (WRITER17) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 5 version 67
   [junit4]   2> 2921472 INFO  (WRITER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=8 
   [junit4]   2> 2921472 INFO  (WRITER7) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit start
   [junit4]   2> 2921473 INFO  (WRITER17) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921473 INFO  (WRITER21) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=4 
   [junit4]   2> 2921443 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 QTime=0 
   [junit4]   2> 2921442 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0 
   [junit4]   2> 2921442 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0 
   [junit4]   2> 2921473 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921473 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921442 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:34&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921442 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0 
   [junit4]   2> 2921441 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0 
   [junit4]   2> 2921441 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=112&wt=json} status=0 QTime=0 
   [junit4]   2> 2921475 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921441 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=129&wt=json} status=0 QTime=0 
   [junit4]   2> 2921441 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921473 INFO  (WRITER21) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 6 version 68
   [junit4]   2> 2921473 INFO  (WRITER17) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit start
   [junit4]   2> 2921476 INFO  (WRITER17) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 2921477 INFO  (WRITER21) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921473 INFO  (WRITER16) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=11 
   [junit4]   2> 2921477 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 7 version 70
   [junit4]   2> 2921473 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 2921472 INFO  (WRITER15) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=7 
   [junit4]   2> 2921478 INFO  (WRITER15) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 85 val= 3 version 71
   [junit4]   2> 2921472 INFO  (WRITER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={} status=0 QTime=69 
   [junit4]   2> 2921481 INFO  (WRITER11) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit end
   [junit4]   2> 2921482 INFO  (WRITER11) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 171 val= 2 version 72
   [junit4]   2> 2921472 INFO  (WRITER20) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 109 val= 5 version 66
   [junit4]   2> 2921482 INFO  (WRITER20) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=66&versions=true&wt=json&indent=true} status=0 QTime=0 
   [junit4]   2> 2921482 INFO  (WRITER20) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 109 val= 5 version 66 DONE
   [junit4]   2> 2921472 INFO  (searcherExecutor-9393-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@173d197[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c6/3:delGen=2)
 Uninverting(_2(6.0.0):c20/8:delGen=2) Uninverting(_3(6.0.0):c1) 
Uninverting(_6(6.0.0):c1) Uninverting(_5(6.0.0):c3) 
Uninverting(_7(6.0.0):c6/2:delGen=1)))}
   [junit4]   2> 2921471 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 2921471 INFO  (WRITER1) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 37 val= 2 version 65
   [junit4]   2> 2921484 INFO  (WRITER20) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 171 val= 2 version 73
   [junit4]   2> 2921487 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=173&wt=json} status=0 QTime=16 
   [junit4]   2> 2921471 INFO  (WRITER18) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 130 val= 2 version 64
   [junit4]   2> 2921488 INFO  (WRITER17) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@14f53f2[collection1] main
   [junit4]   2> 2921488 INFO  (WRITER17) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 2921489 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=145&wt=json} status=0 QTime=14 
   [junit4]   2> 2921489 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:61&omitHeader=true&wt=json} hits=0 status=0 
QTime=12 
   [junit4]   2> 2921471 INFO  (WRITER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=9 
   [junit4]   2> 2921470 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:176&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921470 INFO  (WRITER3) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 2921490 INFO  (WRITER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=6 
   [junit4]   2> 2921467 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:24&omitHeader=true&wt=json} 
hits=1 status=0 QTime=0 
   [junit4]   2> 2921491 INFO  (searcherExecutor-9393-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14f53f2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c6/3:delGen=2)
 Uninverting(_2(6.0.0):c20/10:delGen=2) Uninverting(_6(6.0.0):c1) 
Uninverting(_5(6.0.0):c3/2) Uninverting(_7(6.0.0):c6/2:delGen=1) 
Uninverting(_8(6.0.0):c12/4:delGen=1)))}
   [junit4]   2> 2921491 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=166&wt=json} status=0 QTime=17 
   [junit4]   2> 2921491 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:37&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921491 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=143&wt=json} status=0 QTime=0 
   [junit4]   2> 2921492 INFO  (WRITER17) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={} status=0 QTime=14 
   [junit4]   2> 2921492 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:155&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921495 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=171&wt=json} status=0 
QTime=4 
   [junit4]   2> 2921495 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=150&wt=json} status=0 QTime=6 
   [junit4]   2> 2921495 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0 
   [junit4]   2> 2921496 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1eb2179[collection1] main
   [junit4]   2> 2921466 INFO  (WRITER0) [    ] o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2921460 INFO  (WRITER8) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 135 val= 3 version 61
   [junit4]   2> 2921460 INFO  (WRITER13) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 69 val= 2 version 60
   [junit4]   2> 2921497 INFO  (WRITER17) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit end
   [junit4]   2> 2921460 INFO  (WRITER5) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 14 val= 5 version 49 DONE
   [junit4]   2> 2921460 INFO  (WRITER14) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 75 val= 2 version 54 DONE
   [junit4]   2> 2921497 INFO  (WRITER14) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 6 version 78
   [junit4]   2> 2921460 INFO  (WRITER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=56&versions=true&wt=json&indent=true} status=0 QTime=0 
   [junit4]   2> 2921459 INFO  (WRITER19) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 75 val= 3 version 59
   [junit4]   2> 2921459 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:39&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921459 INFO  (WRITER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=0 
   [junit4]   2> 2921458 INFO  (WRITER12) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit start
   [junit4]   2> 2921498 INFO  (WRITER6) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 15 val= 2 version 79
   [junit4]   2> 2921498 INFO  (WRITER10) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 75 val= 2 version 56 DONE
   [junit4]   2> 2921499 INFO  (WRITER10) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 52 val= 2 version 80
   [junit4]   2> 2921497 INFO  (WRITER15) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=19 
   [junit4]   2> 2921497 INFO  (WRITER5) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 14 val= 6 version 77
   [junit4]   2> 2921497 INFO  (WRITER17) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 37 val= 3 version 76
   [junit4]   2> 2921497 INFO  (searcherExecutor-9393-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1eb2179[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c6/3:delGen=2)
 Uninverting(_2(6.0.0):c20/10:delGen=2) Uninverting(_6(6.0.0):c1) 
Uninverting(_5(6.0.0):c3/2) Uninverting(_7(6.0.0):c6/2:delGen=1) 
Uninverting(_8(6.0.0):c12/5:delGen=1) Uninverting(_9(6.0.0):c3)))}
   [junit4]   2> 2921497 INFO  (WRITER2) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 2921496 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=164&wt=json} status=0 QTime=1 
   [junit4]   2> 2921501 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=139&wt=json} status=0 QTime=0 
   [junit4]   2> 2921501 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:27&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921496 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=36&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921502 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921502 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921496 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=172&wt=json} status=0 QTime=0 
   [junit4]   2> 2921504 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0 
   [junit4]   2> 2921494 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=171&wt=json} status=0 QTime=4 
   [junit4]   2> 2921493 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 
QTime=3 
   [junit4]   2> 2921493 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=3 
   [junit4]   2> 2921506 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:148&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921507 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=145&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921493 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=171&wt=json} status=0 QTime=3 
   [junit4]   2> 2921493 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=1 
   [junit4]   2> 2921491 INFO  (WRITER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=72&versions=true&wt=json&indent=true} status=0 QTime=8 
   [junit4]   2> 2921508 INFO  (WRITER11) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 171 val= 2 version 72 DONE
   [junit4]   2> 2921508 INFO  (WRITER11) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 0 val= 3 version 82
   [junit4]   2> 2921508 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> 2921491 INFO  (WRITER1) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 37 val= 3 version 75
   [junit4]   2> 2921490 INFO  (WRITER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={} status=0 QTime=34 
   [junit4]   2> 2921490 INFO  (WRITER18) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=1 
   [junit4]   2> 2921510 INFO  (WRITER18) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 130 val= 3 version 83
   [junit4]   2> 2921490 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:109&omitHeader=true&wt=json} hits=1 status=0 
QTime=15 
   [junit4]   2> 2921490 INFO  (WRITER4) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 171 val= 2 version 74
   [junit4]   2> 2921488 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=12 
   [junit4]   2> 2921512 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0 
   [junit4]   2> 2921488 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=64&wt=json} status=0 QTime=12 
   [junit4]   2> 2921513 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:120&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921487 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 
QTime=10 
   [junit4]   2> 2921487 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=10 
   [junit4]   2> 2921477 INFO  (WRITER21) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 7 version 69
   [junit4]   2> 2921514 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=166&wt=json} status=0 QTime=0 
   [junit4]   2> 2921514 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921513 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=93&wt=json} status=0 QTime=0 
   [junit4]   2> 2921516 INFO  (WRITER21) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=1 
   [junit4]   2> 2921513 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:130&omitHeader=true&wt=json} hits=1 status=0 
QTime=0 
   [junit4]   2> 2921512 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0 
   [junit4]   2> 2921517 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=139&wt=json} status=0 QTime=0 
   [junit4]   2> 2921518 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 
   [junit4]   2> 2921518 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:109&omitHeader=true&wt=json} hits=1 status=0 
QTime=0 
   [junit4]   2> 2921518 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0 
   [junit4]   2> 2921510 INFO  (WRITER3) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
softCommit end
   [junit4]   2> 2921519 INFO  (WRITER3) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 8 version 85
   [junit4]   2> 2921510 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening Searcher@1554827[collection1] main
   [junit4]   2> 2921519 INFO  (WRITER7) [    ] o.a.s.u.UpdateHandler 
end_commit_flush
   [junit4]   2> 2921520 INFO  (WRITER16) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=42 
   [junit4]   2> 2921520 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 8 version 86
   [junit4]   2> 2921520 INFO  (searcherExecutor-9393-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1554827[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):c6/3:delGen=2)
 Uninverting(_2(6.0.0):c20/10:delGen=2) Uninverting(_5(6.0.0):c3/2) 
Uninverting(_7(6.0.0):c6/3:delGen=1) Uninverting(_8(6.0.0):c12/5:delGen=1) 
Uninverting(_9(6.0.0):c3/1) Uninverting(_a(6.0.0):c5)))}
   [junit4]   2> 2921521 INFO  (WRITER16) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921521 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 109 val= 9 version 87
   [junit4]   2> 2921522 INFO  (WRITER16) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921522 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 157 val= 2 version 88
   [junit4]   2> 2921522 INFO  (WRITER16) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921523 INFO  (WRITER16) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
hardCommit start
   [junit4]   2> 2921509 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=35&wt=json} status=0 QTime=0 
   [junit4]   2> 2921508 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0 
   [junit4]   2> 2921523 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:141&omitHeader=true&wt=json} hits=0 status=0 
QTime=0 
   [junit4]   2> 2921507 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=id:108&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0 
   [junit4]   2> 2921524 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=102&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921524 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=157&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921525 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921525 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921525 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921507 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 
QTime=0 
   [junit4]   2> 2921506 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 
QTime=2 
   [junit4]   2> 2921506 INFO  (READER6) [    ] 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> 2921526 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=157&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921527 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=178&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921506 INFO  (WRITER8) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={versions=true&wt=json&indent=true} status=0 
QTime=8 
   [junit4]   2> 2921527 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={qt=/get&ids=138&wt=json} status=0 
QTime=0 
   [junit4]   2> 2921527 INFO  (WRITER18) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null 
params={del_version=83&versions=true&wt=json&indent=true} status=0 QTime=16 
   [junit4]   2> 2921527 INFO  (WRITER18) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
deleting id 130 val= 3 version 83 DONE
   [junit4]   2> 2921527 INFO  (WRITER18) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 140 val= 2 version 90
   [junit4]   2> 2921528 INFO  (WRITER11) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=18 
   [junit4]   2> 2921528 INFO  (WRITER11) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 157 val= 3 version 91
   [junit4]   2> 2921528 INFO  (WRITER18) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=0 
   [junit4]   2> 2921528 INFO  (WRITER13) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={versions=true&wt=json&indent=true} 
status=0 QTime=31 
   [junit4]   2> 2921529 INFO  (WRITER13) [    ] o.a.s.SolrTestCaseJ4 VERBOSE: 
adding id 140 val= 3 version 93
   [junit4]   2> 2921529 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
webapp=null path=null params={qt=/get&ids=157&wt=json} status=0 QTime=0 
   [junit4]   2> 2921530 INFO  (WRIT

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

lr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\
 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\;done=false>>]
   [junit4]   2> 2924515 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\
   [junit4]   2> 2924515 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\index
 
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\index;done=false>>]
   [junit4]   2> 2924515 INFO  
(SUITE-TestStressUserVersions-seed#[E91ADE7A45E625A0]-worker) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestStressUserVersions_E91ADE7A45E625A0-001\init-core-data-001\index
   [junit4]   2> NOTE: test params are: codec=Lucene53, 
sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=cs, 
timezone=Asia/Krasnoyarsk
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_60 
(32-bit)/cpus=3,threads=1,free=146867240,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestCloudManagedSchemaConcurrent, HdfsChaosMonkeySafeLeaderTest, 
TestCSVLoader, TestRemoteStreaming, OutOfBoxZkACLAndCredentialsProvidersTest, 
VMParamsZkACLAndCredentialsProvidersTest, TestDocBasedVersionConstraints, 
LeaderFailoverAfterPartitionTest, TestStressReorder, AsyncMigrateRouteKeyTest, 
TestReplicationHandler, PathHierarchyTokenizerFactoryTest, 
TestCloudInspectUtil, TestManagedResourceStorage, PrimitiveFieldTypeTest, 
TestStressLucene, TestSolrJ, SegmentsInfoRequestHandlerTest, TestFastWriter, 
TestConfigSets, FileUtilsTest, LeaderElectionTest, RemoteQueryErrorTest, 
AnalysisErrorHandlingTest, TestCloudManagedSchema, TestBlobHandler, 
DistributedExpandComponentTest, TestPseudoReturnFields, CursorMarkTest, 
TestFieldTypeResource, CoreMergeIndexesAdminHandlerTest, 
ExitableDirectoryReaderTest, HLLUtilTest, ShardRoutingTest, 
ConnectionManagerTest, DocumentBuilderTest, TestIntervalFaceting, 
HdfsThreadLeakTest, HardAutoCommitTest, SolrCoreTest, TestDocSet, 
TestStressVersions, TestComponentsName, TestInitParams, 
TestSortByMinMaxFunction, RecoveryAfterSoftCommitTest, 
SharedFSAutoReplicaFailoverUtilsTest, TestExactStatsCache, JSONWriterTest, 
SpatialFilterTest, TestDefaultSearchFieldResource, TestGroupingSearch, 
TestJmxIntegration, DistributedFacetPivotWhiteBoxTest, TestConfig, 
HdfsBasicDistributedZkTest, TestReqParamsAPI, DistributedVersionInfoTest, 
DistributedIntervalFacetingTest, DistributedFacetPivotSmallTest, 
TestSurroundQueryParser, HdfsCollectionsAPIDistributedZkTest, SmileWriterTest, 
CustomCollectionTest, CurrencyFieldXmlFileTest, TestSchemaSimilarityResource, 
SimpleCollectionCreateDeleteTest, HdfsSyncSliceTest, TestSolrXml, 
TermsComponentTest, FastVectorHighlighterTest, OverseerRolesTest, 
TestSolrConfigHandler, MigrateRouteKeyTest, RulesTest, SolrXmlInZkTest, 
AlternateDirectoryTest, QueryElevationComponentTest, SampleTest, 
HdfsBasicDistributedZk2Test, TestConfigSetProperties, TestSchemaManager, 
MBeansHandlerTest, TestQuerySenderNoQuery, RollingRestartTest, SyncSliceTest, 
DocValuesTest, DistributedQueryComponentOptimizationTest, 
TestPartialUpdateDeduplication, TestTrackingShardHandlerFactory, 
TestScoreJoinQPScore, TestImplicitCoreProperties, AnalysisAfterCoreReloadTest, 
TestDistributedMissingSort, TestNoOpRegenerator, SolrRequestParserTest, 
SuggestComponentTest, TestFieldCollectionResource, TestJettySolrRunner, 
HighlighterTest, TestFieldSortValues, DeleteReplicaTest, TestCodecSupport, 
TestRequestStatusCollectionAPI, TestRTGBase, TestSchemaNameResource, 
OverseerTest, CloudExitableDirectoryReaderTest, 
TestManagedSchemaDynamicFieldResource, StandardRequestHandlerTest, 
DistribDocExpirationUpdateProcessorTest, TestPostingsSolrHighlighter, 
JsonLoaderTest, TestCloudSchemaless, DeleteShardTest, 
TestCursorMarkWithoutUniqueKey, TestDistribIDF, TestLazyCores, 
DocumentAnalysisRequestHandlerTest, TestLMDirichletSimilarityFactory, 
CoreAdminRequestStatusTest, DirectUpdateHandlerTest, TestHashQParserPlugin, 
DeleteLastCustomShardedReplicaTest, SystemInfoHandlerTest, ZkNodePropsTest, 
TestSolrDeletionPolicy1, HLLSerializationTest, BadComponentTest, 
ExplicitHLLTest, BJQParserTest, TestRandomDVFaceting, TestExceedMaxTermLength, 
OpenCloseCoreStressTest, LeaderElectionIntegrationTest, RecoveryZkTest, 
ClusterStateUpdateTest, ZkCLITest, AutoCommitTest, TestFaceting, TestRecovery, 
TestHashPartitioner, DistributedSpellCheckComponentTest, ZkControllerTest, 
TestRealTimeGet, TestReload, DistributedTermsComponentTest, TestCoreContainer, 
SimpleFacetsTest, TestSolr4Spatial, PeerSyncTest, ConvertedLegacyTest, 
TestFunctionQuery, TestBadConfig, CurrencyFieldOpenExchangeTest, 
DistributedQueryElevationComponentTest, PolyFieldTest, NoCacheHeaderTest, 
TestUpdate, TestWordDelimiterFilterFactory, 
StatelessScriptUpdateProcessorFactoryTest, DocValuesMultiTest, 
SolrInfoMBeanTest, IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, 
TestQueryUtils, DirectSolrSpellCheckerTest, TestQueryTypes, 
TestValueSourceCache, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, 
TestArbitraryIndexDir, RegexBoostProcessorTest, TestCollationField, 
ReturnFieldsTest, UniqFieldsUpdateProcessorFactoryTest, 
TestPHPSerializedResponseWriter, SearchHandlerTest, SOLR749Test, 
UpdateParamsTest, TestSolrIndexConfig, SolrIndexConfigTest, TestStressRecovery, 
TestMergePolicyConfig, MultiTermTest, TestSearchPerf, MinimalSchemaTest, 
TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions, 
ExternalFileFieldSortTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, 
TestLuceneMatchVersion, TestPhraseSuggestions, TestCharFilters, 
SynonymTokenizerTest, TestXIncludeConfig, EchoParamsTest, 
TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, 
TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, 
TestIBSimilarityFactory, TestBM25SimilarityFactory, 
TestDefaultSimilarityFactory, TestJmxMonitoredMap, ResourceLoaderTest, 
OpenExchangeRatesOrgProviderTest, PluginInfoTest, TestFastLRUCache, 
ChaosMonkeyNothingIsSafeTest, BaseCdcrDistributedZkTest, 
CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, 
ConcurrentDeleteAndCreateCollectionTest, DeleteInactiveReplicaTest, 
OverriddenZkACLAndCredentialsProvidersTest, 
OverseerCollectionConfigSetProcessorTest, OverseerTaskQueueTest, 
SSLMigrationTest, SaslZkACLProviderTest, SolrCloudExampleTest, 
TestAuthenticationFramework, TestCryptoKeys, TestDownShardTolerantSearch, 
TestMiniSolrCloudCluster, TestReplicaProperties, TestSolrCloudWithKerberosAlt, 
RuleEngineTest, CachingDirectoryFactoryTest, TestNRTOpen, 
TestShardHandlerFactory, RequestLoggingTest, TestReplicationHandlerBackup, 
TestSQLHandler, CoreAdminCreateDiscoverTest, InfoHandlerTest, 
DistributedDebugComponentTest, DistributedFacetPivotLargeTest, 
DistributedSuggestComponentTest, TestExpandComponent, TestPivotHelperCode, 
JavabinLoaderTest, HighlighterMaxOffsetTest, TestChildDocTransformer, 
TestRawResponseWriter, TestSortingResponseWriter, TestManagedResource, 
TestBulkSchemaAPI, TestClassNameShortening, TestCopyFieldCollectionResource, 
TestDynamicFieldCollectionResource, TestDynamicFieldResource, 
TestFieldResource, TestFieldTypeCollectionResource, 
TestManagedSchemaFieldResource, TestManagedSchemaFieldTypeResource, 
TestRemoveLastDynamicCopyField, TestSchemaResource, TestSchemaVersionResource, 
TestSerializedLuceneMatchVersion, TestManagedStopFilterFactory, 
TestManagedSynonymFilterFactory, BadCopyFieldTest, ChangedSchemaMergeTest, 
DocValuesMissingTest, TestBulkSchemaConcurrent, TestCollationFieldDocValues, 
TestManagedSchema, AnalyticsMergeStrategyTest, MergeStrategyTest, 
TestAddFieldRealTimeGet, TestComplexPhraseQParserPlugin, TestInitQParser, 
TestOverriddenPrefixQueryForCustomFieldType, TestReloadDeadlock, 
TestSimpleQParserPlugin, TestStandardQParsers, TestStressUserVersions]
   [junit4] Completed [513/543] on J0 in 3.55s, 1 test, 1 error <<< FAILURES!

[...truncated 101 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:775: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:719: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:59: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:233: 
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:514:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1432:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:987:
 There were test failures: 543 suites, 2147 tests, 1 error, 80 ignored (44 
assumptions)

Total time: 65 minutes 31 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: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to