Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4657/
Java: 32bit/jdk1.8.0_45 -server -XX:+UseConcMarkSweepGC

2 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.LargeVolumeJettyTest

Error Message:
Some resources were not closed, shutdown, or released.

Stack Trace:
java.lang.AssertionError: Some resources were not closed, shutdown, or released.
        at __randomizedtesting.SeedInfo.seed([5AEA969D5ED84A9C]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:234)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  
junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.LargeVolumeJettyTest

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 
5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog\tlog.0000000000000000002:
 java.nio.file.FileSystemException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 
5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog\tlog.0000000000000000002:
 The process cannot access the file because it is being used by another 
process.     
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 
5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog\tlog.0000000000000000002:
 java.nio.file.FileSystemException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 
5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog\tlog.0000000000000000002:
 The process cannot access the file because it is being used by another process.

   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001
   
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001

        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:294)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:215)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11652 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.embedded.LargeVolumeJettyTest
   [junit4]   2> Creating dataDir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\init-core-data-001
   [junit4]   2> 94113 T268 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (true)
   [junit4]   2> 94312 T268 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 94313 T268 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 94318 T268 oas.SolrTestCaseJ4.writeCoreProperties Writing 
core.properties file to 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-002\cores\core
   [junit4]   2> 94329 T268 oejs.Server.doStart jetty-9.2.10.v20150310
   [junit4]   2> 94343 T268 oejsh.ContextHandler.doStart Started 
o.e.j.s.ServletContextHandler@53e997{/solr,null,AVAILABLE}
   [junit4]   2> 94359 T268 oejs.AbstractConnector.doStart Started 
ServerConnector@13c8af4{HTTP/1.1}{127.0.0.1:57064}
   [junit4]   2> 94360 T268 oejs.Server.doStart Started @95055ms
   [junit4]   2> 94360 T268 oascse.JettySolrRunner$1.lifeCycleStarted Jetty 
properties: {hostContext=/solr, hostPort=57064, 
configSetBaseDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001, 
coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-002\cores}
   [junit4]   2> 94361 T268 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@19e0bfd
   [junit4]   2> 94361 T268 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\'
   [junit4]   2> 94401 T268 oasc.SolrXmlConfig.fromFile Loading container 
configuration from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\solr.xml
   [junit4]   2> 94414 T268 oasc.CorePropertiesLocator.<init> Config-defined 
core root directory: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001
   [junit4]   2> 94415 T268 oasc.CoreContainer.<init> New CoreContainer 17786296
   [junit4]   2> 94415 T268 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\]
   [junit4]   2> 94416 T268 oasc.CoreContainer.load loading shared library: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\lib
   [junit4]   2> 94416 T268 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: lib (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\lib).
   [junit4]   2> 94439 T268 oashc.HttpShardHandlerFactory.init created with 
socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : 
false,useRetries : false,
   [junit4]   2> 94443 T268 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=600000&connTimeout=60000&retry=true
   [junit4]   2> 94444 T268 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 94446 T268 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 94446 T268 oasc.CoreContainer.load Node Name: 
   [junit4]   2> 94452 T268 oasc.CorePropertiesLocator.discover Looking for 
core definitions underneath 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001
   [junit4]   2> 94456 T268 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: 
{name=collection1, config=solrconfig.xml, 
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1, loadOnStartup=true, 
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\, schema=schema.xml, 
transient=false, dataDir=data\}
   [junit4]   2> 94456 T268 oasc.CorePropertiesLocator.discoverUnder Found core 
collection1 in 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\
   [junit4]   2> 94457 T268 oasc.CorePropertiesLocator.discover Found 1 core 
definitions
   [junit4]   2> 94458 T280 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\'
   [junit4]   2> 94506 T280 oasc.SolrConfig.refreshRequestParams current 
version of requestparams : -1
   [junit4]   2> 94507 T280 oasc.SolrConfig.initLibs Adding specified lib dirs 
to ClassLoader
   [junit4]   2> 94507 T280 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: 
../../../../contrib/extraction/lib (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 
5AEA969D5ED84A9C-001\tempDir-001\collection1\..\..\..\..\contrib\extraction\lib).
   [junit4]   2> 94508 T280 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../dist/ (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\..\..\..\..\dist).
   [junit4]   2> 94508 T280 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: 
../../../../contrib/clustering/lib/ (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 
5AEA969D5ED84A9C-001\tempDir-001\collection1\..\..\..\..\contrib\clustering\lib).
   [junit4]   2> 94508 T280 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../dist/ (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\..\..\..\..\dist).
   [junit4]   2> 94511 T280 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../contrib/langid/lib/ 
(resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\..\..\..\..\contrib\langid\lib).
   [junit4]   2> 94511 T280 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../dist/ (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\..\..\..\..\dist).
   [junit4]   2> 94511 T280 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: 
../../../../contrib/velocity/lib (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\..\..\..\..\contrib\velocity\lib).
   [junit4]   2> 94511 T280 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../../dist/ (resolved as: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\..\..\..\..\dist).
   [junit4]   2> 94550 T280 oasu.SolrIndexConfig.<init> IndexWriter infoStream 
solr logging is enabled
   [junit4]   2> 94553 T280 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
5.2.0
   [junit4]   2> 94603 T280 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 94604 T280 oass.IndexSchema.readSchema Reading Solr Schema 
from 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf\schema.xml
   [junit4]   2> 94611 T280 oass.IndexSchema.readSchema [collection1] Schema 
name=example
   [junit4]   2> 94912 T280 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 94912 T280 oasr.RestManager$Registry.registerManagedResource 
Registered ManagedResource impl 
org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path 
/schema/analysis/stopwords/english
   [junit4]   2> 94912 T280 oasr.RestManager$Registry.registerManagedResource 
Registered ManagedResource impl 
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager 
for path /schema/analysis/synonyms/english
   [junit4]   2> 94932 T280 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 94937 T280 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 94983 T280 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\
   [junit4]   2> 94983 T280 x:collection1 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 94983 T280 x:collection1 oasc.SolrCore.<init> [[collection1] ] 
Opening new SolrCore at 
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\], dataDir=[null]
   [junit4]   2> 94983 T280 x:collection1 oasc.JmxMonitoredMap.<init> JMX 
monitoring is enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@15476e7
   [junit4]   2> 94983 T280 x:collection1 oasc.SolrCore.initListeners 
[[collection1] ] Added SolrEventListener for newSearcher: 
[org.apache.solr.core.QuerySenderListener{queries=[]}]
   [junit4]   2> 94983 T280 x:collection1 oasc.SolrCore.initListeners 
[[collection1] ] Added SolrEventListener for firstSearcher: 
[org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher 
warming in solrconfig.xml}]}]
   [junit4]   2> 94984 T280 x:collection1 oasc.CachingDirectoryFactory.get 
return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\
   [junit4]   2> 94986 T280 x:collection1 oasc.SolrCore.getNewIndexDir New 
index directory detected: old=null 
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\index/
   [junit4]   2> 94986 T280 x:collection1 oasc.SolrCore.initIndex WARN 
[collection1] Solr index directory 
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\index' doesn't exist. 
Creating new index...
   [junit4]   2> 94988 T280 x:collection1 oasc.CachingDirectoryFactory.get 
return new directory for 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\index
   [junit4]   2> 94988 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: init: current segments file is 
"segments"; 
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1b3b4f0
   [junit4]   2> 94988 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: now checkpoint "" [0 segments ; isCommit 
= false]
   [junit4]   2> 94988 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: 0 msec to checkpoint
   [junit4]   2> 94989 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: init: create=true
   [junit4]   2> 94989 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: 
   [junit4]   2>        dir=MockDirectoryWrapper(RAMDirectory@9666b5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f33b45)
   [junit4]   2>        index=
   [junit4]   2>        version=5.2.0
   [junit4]   2>        
analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
   [junit4]   2>        ramBufferSizeMB=100.0
   [junit4]   2>        maxBufferedDocs=-1
   [junit4]   2>        maxBufferedDeleteTerms=-1
   [junit4]   2>        mergedSegmentWarmer=null
   [junit4]   2>        
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
   [junit4]   2>        commit=null
   [junit4]   2>        openMode=CREATE
   [junit4]   2>        
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
   [junit4]   2>        mergeScheduler=ConcurrentMergeScheduler: 
maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
   [junit4]   2>        default WRITE_LOCK_TIMEOUT=1000
   [junit4]   2>        writeLockTimeout=1000
   [junit4]   2>        codec=Lucene50
   [junit4]   2>        infoStream=org.apache.solr.update.LoggingInfoStream
   [junit4]   2>        mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2>        
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@1c62e7b
   [junit4]   2>        readerPooling=false
   [junit4]   2>        perThreadHardLimitMB=1945
   [junit4]   2>        useCompoundFile=false
   [junit4]   2>        commitOnClose=true
   [junit4]   2>        writer=org.apache.solr.update.SolrIndexWriter@c645f8
   [junit4]   2>        
   [junit4]   2> 94989 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: now flush at close
   [junit4]   2> 94989 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]:   start flush: applyAllDeletes=true
   [junit4]   2> 94990 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]:   index before flush 
   [junit4]   2> 94990 T280 x:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-160-thread-1]: startFullFlush
   [junit4]   2> 94990 T280 x:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-160-thread-1]: coreLoadExecutor-160-thread-1 
finishFullFlush success=true
   [junit4]   2> 94990 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: apply all deletes during flush
   [junit4]   2> 94990 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 94992 T280 x:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-160-thread-1]: prune sis=segments:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 94992 T280 x:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-160-thread-1]: initDynamicDefaults spins=true 
maxThreadCount=1 maxMergeCount=6
   [junit4]   2> 94992 T280 x:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-160-thread-1]: updateMergeThreads ioThrottle=true 
targetMBPerSec=10240.0 MB/sec
   [junit4]   2> 94993 T280 x:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-160-thread-1]: now merge
   [junit4]   2> 94993 T280 x:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-160-thread-1]:   index: 
   [junit4]   2> 94993 T280 x:collection1 oasu.LoggingInfoStream.message 
[MS][coreLoadExecutor-160-thread-1]:   no more merges pending; now return
   [junit4]   2> 94993 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: waitForMerges
   [junit4]   2> 94993 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: waitForMerges done
   [junit4]   2> 94993 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: commit: start
   [junit4]   2> 94993 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: commit: enter lock
   [junit4]   2> 94994 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: commit: now prepare
   [junit4]   2> 94994 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: prepareCommit: flush
   [junit4]   2> 94994 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]:   index before flush 
   [junit4]   2> 94994 T280 x:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-160-thread-1]: startFullFlush
   [junit4]   2> 94995 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: apply all deletes during flush
   [junit4]   2> 94995 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 94995 T280 x:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-160-thread-1]: prune sis=segments:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 94995 T280 x:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-160-thread-1]: coreLoadExecutor-160-thread-1 
finishFullFlush success=true
   [junit4]   2> 94995 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: startCommit(): start
   [junit4]   2> 94995 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: startCommit index= changeCount=1
   [junit4]   2> 94997 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: done all syncs: []
   [junit4]   2> 94997 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: commit: pendingCommit != null
   [junit4]   2> 94999 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: now checkpoint "" [0 segments ; isCommit 
= true]
   [junit4]   2> 94999 T280 x:collection1 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@9666b5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f33b45),segFN=segments_1,generation=1}
   [junit4]   2> 94999 T280 x:collection1 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 1
   [junit4]   2> 94999 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: 0 msec to checkpoint
   [junit4]   2> 94999 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: commit: wrote segments file "segments_1"
   [junit4]   2> 95000 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: commit: took 4.7 msec
   [junit4]   2> 95000 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: commit: done
   [junit4]   2> 95000 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: rollback
   [junit4]   2> 95000 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: all running merges have aborted
   [junit4]   2> 95001 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: rollback: done finish merges
   [junit4]   2> 95001 T280 x:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-160-thread-1]: abort
   [junit4]   2> 95001 T280 x:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-160-thread-1]: done abort success=true
   [junit4]   2> 95002 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: rollback: infos=
   [junit4]   2> 95002 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: now checkpoint "" [0 segments ; isCommit 
= false]
   [junit4]   2> 95003 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: 0 msec to checkpoint
   [junit4]   2> 95003 T280 x:collection1 oasc.PluginBag.createPlugin 
queryResponseWriter : 'velocity' created with startup=lazy 
   [junit4]   2> 95004 T280 x:collection1 oasr.XSLTResponseWriter.init 
xsltCacheLifetimeSeconds=5
   [junit4]   2> 95017 T280 x:collection1 
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined 
as default, creating implicit default
   [junit4]   2> 95032 T280 x:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 95036 T280 x:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 95039 T280 x:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 95048 T280 x:collection1 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
   [junit4]   2> 95062 T280 x:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/update/extract' created with startup=lazy 
   [junit4]   2> 95062 T280 x:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/analysis/field' created with startup=lazy 
   [junit4]   2> 95062 T280 x:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/analysis/document' created with startup=lazy 
   [junit4]   2> 95063 T280 x:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/spell' created with startup=lazy 
   [junit4]   2> 95063 T280 x:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/suggest' created with startup=lazy 
   [junit4]   2> 95063 T280 x:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/tvrh' created with startup=lazy 
   [junit4]   2> 95063 T280 x:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/terms' created with startup=lazy 
   [junit4]   2> 95063 T280 x:collection1 oasc.PluginBag.createPlugin 
requestHandler : '/elevate' created with startup=lazy 
   [junit4]   2> 95063 T280 x:collection1 
oasc.RequestHandlers.initHandlersFromConfig Registered paths: 
/admin/mbeans,/browse,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/elevate,/update/json,/admin/threads,/query,/analysis/field,/analysis/document,/spell,/update/csv,/tvrh,/select,/admin/segments,/admin/system,/suggest,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/debug/dump,/terms,/update/extract
   [junit4]   2> 95066 T280 x:collection1 oasc.SolrCore.initStatsCache Using 
default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 95068 T280 x:collection1 oasu.UpdateHandler.<init> Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 95068 T280 x:collection1 oasu.UpdateLog.init Initializing 
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10
   [junit4]   2> 95070 T280 x:collection1 oasu.CommitTracker.<init> Hard 
AutoCommit: if uncommited for 15000ms; 
   [junit4]   2> 95070 T280 x:collection1 oasu.CommitTracker.<init> Soft 
AutoCommit: disabled
   [junit4]   2> 95071 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: init: current segments file is 
"segments_1"; 
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1b3b4f0
   [junit4]   2> 95071 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: init: load commit "segments_1"
   [junit4]   2> 95071 T280 x:collection1 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@9666b5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f33b45),segFN=segments_1,generation=1}
   [junit4]   2> 95071 T280 x:collection1 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 1
   [junit4]   2> 95072 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: now checkpoint "" [0 segments ; isCommit 
= false]
   [junit4]   2> 95072 T280 x:collection1 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-160-thread-1]: 0 msec to checkpoint
   [junit4]   2> 95072 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: init: create=false
   [junit4]   2> 95078 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: 
   [junit4]   2>        dir=MockDirectoryWrapper(RAMDirectory@9666b5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f33b45)
   [junit4]   2>        index=
   [junit4]   2>        version=5.2.0
   [junit4]   2>        
analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
   [junit4]   2>        ramBufferSizeMB=100.0
   [junit4]   2>        maxBufferedDocs=-1
   [junit4]   2>        maxBufferedDeleteTerms=-1
   [junit4]   2>        mergedSegmentWarmer=null
   [junit4]   2>        
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
   [junit4]   2>        commit=null
   [junit4]   2>        openMode=APPEND
   [junit4]   2>        
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
   [junit4]   2>        mergeScheduler=ConcurrentMergeScheduler: 
maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
   [junit4]   2>        default WRITE_LOCK_TIMEOUT=1000
   [junit4]   2>        writeLockTimeout=1000
   [junit4]   2>        codec=Lucene50
   [junit4]   2>        infoStream=org.apache.solr.update.LoggingInfoStream
   [junit4]   2>        mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2>        
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@85e313
   [junit4]   2>        readerPooling=false
   [junit4]   2>        perThreadHardLimitMB=1945
   [junit4]   2>        useCompoundFile=false
   [junit4]   2>        commitOnClose=true
   [junit4]   2>        writer=org.apache.solr.update.SolrIndexWriter@7ef9c8
   [junit4]   2>        
   [junit4]   2> 95079 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: flush at getReader
   [junit4]   2> 95079 T280 x:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-160-thread-1]: startFullFlush
   [junit4]   2> 95079 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: apply all deletes during flush
   [junit4]   2> 95080 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: now apply all deletes for all segments 
maxDoc=0
   [junit4]   2> 95080 T280 x:collection1 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-160-thread-1]: prune sis=segments_1:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 95080 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: return reader version=1 
reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 95080 T280 x:collection1 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-160-thread-1]: coreLoadExecutor-160-thread-1 
finishFullFlush success=true
   [junit4]   2> 95080 T280 x:collection1 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-160-thread-1]: getReader took 1 msec
   [junit4]   2> 95082 T280 x:collection1 oass.SolrIndexSearcher.<init> Opening 
Searcher@19fdcca[collection1] main
   [junit4]   2> 95083 T280 x:collection1 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf
   [junit4]   2> 95084 T280 x:collection1 oasr.RestManager.init Initializing 
RestManager with initArgs: 
{storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf}
   [junit4]   2> 95084 T280 x:collection1 oasr.ManagedResourceStorage.load 
Reading _rest_managed.json using 
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf
   [junit4]   2> 95084 T280 x:collection1 oasr.ManagedResourceStorage.load 
Loaded LinkedHashMap at path _rest_managed.json using 
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf
   [junit4]   2> 95085 T280 x:collection1 
oasr.ManagedResource.processStoredData Loaded initArgs {} for /rest/managed
   [junit4]   2> 95085 T280 x:collection1 oasr.RestManager.init Initializing 2 
registered ManagedResources
   [junit4]   2> 95085 T280 x:collection1 oasr.ManagedResourceStorage.load 
Reading _schema_analysis_stopwords_english.json using 
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf
   [junit4]   2> 95085 T280 x:collection1 oasr.ManagedResourceStorage.load 
Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using 
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf
   [junit4]   2> 95085 T280 x:collection1 
oasr.ManagedResource.processStoredData Loaded initArgs {ignoreCase=true} for 
/schema/analysis/stopwords/english
   [junit4]   2> 95085 T280 x:collection1 
oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 35 words 
for /schema/analysis/stopwords/english
   [junit4]   2> 95086 T280 x:collection1 
oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of 
/schema/analysis/stopwords/english
   [junit4]   2> 95086 T280 x:collection1 oasr.ManagedResourceStorage.load 
Reading _schema_analysis_synonyms_english.json using 
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf
   [junit4]   2> 95089 T280 x:collection1 oasr.ManagedResourceStorage.load 
Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using 
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf
   [junit4]   2> 95089 T280 x:collection1 
oasr.ManagedResource.processStoredData Loaded initArgs 
{ignoreCase=true,format=solr} for /schema/analysis/synonyms/english
   [junit4]   2> 95090 T280 x:collection1 
oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage
 Loaded 3 synonym mappings for /schema/analysis/synonyms/english
   [junit4]   2> 95090 T280 x:collection1 
oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of 
/schema/analysis/synonyms/english
   [junit4]   2> 95090 T280 x:collection1 oashc.SpellCheckComponent.inform 
Initializing spell checkers
   [junit4]   2> 95091 T280 x:collection1 oass.DirectSolrSpellChecker.init 
init: 
{name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
   [junit4]   2> 95091 T280 x:collection1 oashc.SpellCheckComponent.inform No 
queryConverter defined, using default converter
   [junit4]   2> 95091 T280 x:collection1 oashc.SuggestComponent.inform 
Initializing SuggestComponent
   [junit4]   2> 95092 T280 x:collection1 oasss.SolrSuggester.init init: 
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
   [junit4]   2> 95092 T280 x:collection1 oasss.SolrSuggester.init Dictionary 
loaded with params: 
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
   [junit4]   2> 95093 T280 x:collection1 oashc.QueryElevationComponent.inform 
Loading QueryElevation from: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\conf\elevate.xml
   [junit4]   2> 95103 T280 x:collection1 oash.ReplicationHandler.inform 
Commits will be reserved for  10000
   [junit4]   2> 95104 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@19fdcca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> ASYNC  NEW_CORE C97 name=collection1 
org.apache.solr.core.SolrCore@131466
   [junit4]   2> 95105 T281 x:collection1 C97 oasc.SolrCore.execute 
[collection1] webapp=null path=null 
params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher}
 hits=0 status=0 QTime=1 
   [junit4]   2> 95105 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 95105 T281 x:collection1 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 95105 T281 x:collection1 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 95106 T281 x:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@19fdcca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 95105 T280 x:collection1 oasc.CoreContainer.registerCore 
registering core: collection1
   [junit4]   2> 95112 T268 oass.SolrDispatchFilter.init 
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1
   [junit4]   2> 95112 T268 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 95112 T268 oas.SolrJettyTestBase.createJetty Jetty Assigned 
Port#57064
   [junit4]   2> 95127 T268 oas.SolrTestCaseJ4.setUp ###Starting 
testMultiThreaded
   [junit4]   2> 95145 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-274]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 95146 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-274]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 95146 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-274]: nrtIsCurrent: infoVersion matches: true; DW changes: 
true; BD changes: false
   [junit4]   2> 95146 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-274]: flush at getReader
   [junit4]   2> 95146 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-274]: startFullFlush
   [junit4]   2> 95146 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-274]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 95147 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-274]: qtp14445605-274: flush naked frozen global deletes
   [junit4]   2> 95148 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-274]: push deletes  1 deleted queries bytesUsed=64 
segmentPrivate?=false delGen=2 packetCount=1 totBytesUsed=64
   [junit4]   2> 95148 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-274]: flush: push buffered updates:  1 deleted queries 
bytesUsed=64
   [junit4]   2> 95148 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-274]: apply all deletes during flush
   [junit4]   2> 95148 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-274]: now apply all deletes for all segments maxDoc=0
   [junit4]   2> 95148 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-274]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=1
   [junit4]   2> 95148 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-274]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 95148 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-274]: return reader version=1 
reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 95151 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-274]: qtp14445605-274 finishFullFlush success=true
   [junit4]   2> 95151 T274 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-274]: getReader took 5 msec
   [junit4]   2> 95152 T274 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{deleteByQuery=*:* (-1500478621838475264)} 0 15
   [junit4]   2> 95154 T268 oascs.LargeVolumeTestBase.testMultiThreaded Started 
thread: 0
   [junit4]   2> 95156 T268 oascs.LargeVolumeTestBase.testMultiThreaded Started 
thread: 1
   [junit4]   2> 95159 T268 oascs.LargeVolumeTestBase.testMultiThreaded Started 
thread: 2
   [junit4]   2> 95160 T268 oascs.LargeVolumeTestBase.testMultiThreaded Started 
thread: 3
   [junit4]   2> 95162 T268 oascs.LargeVolumeTestBase.testMultiThreaded Started 
thread: 4
   [junit4]   2> 95204 T277 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{add=[T0:0 (1500478621888806912), T0:1 (1500478621898244096), T0:2 
(1500478621898244097), T0:3 (1500478621898244098), T0:4 (1500478621898244099), 
T0:5 (1500478621898244100), T0:6 (1500478621898244101), T0:7 
(1500478621898244102), T0:8 (1500478621898244103), T0:9 (1500478621898244104), 
... (100 adds)]} 0 23
   [junit4]   2> 95227 T273 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{add=[T1:0 (1500478621920264192), T1:1 (1500478621920264193), T1:2 
(1500478621920264194), T1:3 (1500478621920264195), T1:4 (1500478621920264196), 
T1:5 (1500478621920264197), T1:6 (1500478621920264198), T1:7 
(1500478621920264199), T1:8 (1500478621920264200), T1:9 (1500478621925507072), 
... (100 adds)]} 0 10
   [junit4]   2> 95231 T271 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95231 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: commit: start
   [junit4]   2> 95231 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: commit: enter lock
   [junit4]   2> 95232 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: commit: now prepare
   [junit4]   2> 95234 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: prepareCommit: flush
   [junit4]   2> 95234 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]:   index before flush 
   [junit4]   2> 95234 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-271]: startFullFlush
   [junit4]   2> 95234 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-271]: anyChanges? numDocsInRam=400 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 95234 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWFC][qtp14445605-271]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0 136 deleted terms (unique count=136) bytesUsed=19157, 
segment=_0, aborted=false, numDocsInRAM=31, deleteQueue=DWDQ: [ generation: 2 ]]
   [junit4]   2> 95234 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWFC][qtp14445605-271]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0 398 deleted terms (unique count=398) bytesUsed=56080, 
segment=_1, aborted=false, numDocsInRAM=369, deleteQueue=DWDQ: [ generation: 2 
]]
   [junit4]   2> 95238 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: flush postings as segment _0 numDocs=31
   [junit4]   2> 95243 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: new segment has 0 deleted docs
   [junit4]   2> 95243 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: new segment has no vectors; norms; no docValues; prox; 
freqs
   [junit4]   2> 95243 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: flushedFiles=[_0_Lucene50_0.doc, _0_Lucene50_0.tim, 
_0_Lucene50_0.pos, _0.nvd, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.nvm, _0.fnm]
   [junit4]   2> 95243 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: flushed codec=Lucene50
   [junit4]   2> 95243 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: flushed: segment=_0 ramUsed=0.15 MB 
newFlushedSize=0.002 MB docs/MB=13,915.178
   [junit4]   2> 95244 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: flush postings as segment _1 numDocs=369
   [junit4]   2> 95248 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: new segment has 0 deleted docs
   [junit4]   2> 95248 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: new segment has no vectors; norms; no docValues; prox; 
freqs
   [junit4]   2> 95249 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: flushedFiles=[_1_Lucene50_0.doc, _1_Lucene50_0.tim, 
_1.nvd, _1_Lucene50_0.pos, _1.fdx, _1.nvm, _1.fnm, _1.fdt, _1_Lucene50_0.tip]
   [junit4]   2> 95249 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: flushed codec=Lucene50
   [junit4]   2> 95250 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-271]: flushed: segment=_1 ramUsed=0.172 MB 
newFlushedSize=0.01 MB docs/MB=37,398.467
   [junit4]   2> 95250 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-271]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 95251 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: publishFlushedSegment
   [junit4]   2> 95251 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: push deletes  400 deleted terms (unique count=400) 
bytesUsed=2080 segmentPrivate?=false delGen=4 packetCount=1 totBytesUsed=2080
   [junit4]   2> 95251 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: publish sets newSegment delGen=5 seg=_0(5.2.0):C31
   [junit4]   2> 95251 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-271]: now checkpoint "_0(5.2.0):C31" [1 segments ; isCommit = 
false]
   [junit4]   2> 95252 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-271]: 0 msec to checkpoint
   [junit4]   2> 95252 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-271]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 95252 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: publishFlushedSegment
   [junit4]   2> 95254 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: publish sets newSegment delGen=6 seg=_1(5.2.0):C369
   [junit4]   2> 95254 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-271]: now checkpoint "_0(5.2.0):C31 _1(5.2.0):C369" [2 
segments ; isCommit = false]
   [junit4]   2> 95254 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-271]: 0 msec to checkpoint
   [junit4]   2> 95254 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: apply all deletes during flush
   [junit4]   2> 95255 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: now apply all deletes for all segments maxDoc=400
   [junit4]   2> 95255 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 95255 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: applyDeletes: infos=[_0(5.2.0):C31, _1(5.2.0):C369] 
packetCount=1
   [junit4]   2> 95255 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: applyDeletes took 0 msec for 2 segments, 0 newly deleted 
docs (query deletes), 0 visited terms, allDeleted=null
   [junit4]   2> 95256 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: prune sis=segments_1: _0(5.2.0):C31 _1(5.2.0):C369 
minGen=5 packetCount=1
   [junit4]   2> 95256 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 95256 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-271]: qtp14445605-271 finishFullFlush success=true
   [junit4]   2> 95257 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-271]: findMerges: 2 segments
   [junit4]   2> 95257 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-271]:   seg=_1(5.2.0):C369 size=0.010 MB [floored]
   [junit4]   2> 95258 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-271]:   seg=_0(5.2.0):C31 size=0.002 MB [floored]
   [junit4]   2> 95258 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-271]:   allowedSegmentCount=1 vs count=2 (eligible count=2) 
tooBigCount=0
   [junit4]   2> 95258 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[MS][qtp14445605-271]: initDynamicDefaults spins=true maxThreadCount=1 
maxMergeCount=6
   [junit4]   2> 95258 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[MS][qtp14445605-271]: now merge
   [junit4]   2> 95258 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[MS][qtp14445605-271]:   index: _0(5.2.0):C31 _1(5.2.0):C369
   [junit4]   2> 95258 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[MS][qtp14445605-271]:   no more merges pending; now return
   [junit4]   2> 95259 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: startCommit(): start
   [junit4]   2> 95259 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: startCommit index=_0(5.2.0):C31 _1(5.2.0):C369 
changeCount=5
   [junit4]   2> 95261 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: done all syncs: [_0.si, _0_Lucene50_0.doc, 
_0_Lucene50_0.tim, _1.si, _1.nvd, _0_Lucene50_0.pos, _0.nvd, _0.fdx, 
_0_Lucene50_0.tip, _1.nvm, _1.fnm, _0.fdt, _1_Lucene50_0.doc, 
_1_Lucene50_0.tim, _1_Lucene50_0.pos, _1.fdx, _0.nvm, _0.fnm, _1.fdt, 
_1_Lucene50_0.tip]
   [junit4]   2> 95262 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: commit: pendingCommit != null
   [junit4]   2> 95262 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-271]: now checkpoint "_0(5.2.0):C31 _1(5.2.0):C369" [2 
segments ; isCommit = true]
   [junit4]   2> 95262 T271 x:collection1 C97 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@9666b5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f33b45),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@9666b5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f33b45),segFN=segments_2,generation=2}
   [junit4]   2> 95263 T271 x:collection1 C97 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 95264 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-271]: deleteCommits: now decRef commit "segments_1"
   [junit4]   2> 95264 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-271]: delete "segments_1"
   [junit4]   2> 95265 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-271]: 2 msec to checkpoint
   [junit4]   2> 95265 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: commit: wrote segments file "segments_2"
   [junit4]   2> 95266 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: commit: took 27.9 msec
   [junit4]   2> 95266 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: commit: done
   [junit4]   2> 95269 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 95270 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: flush at getReader
   [junit4]   2> 95270 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-271]: startFullFlush
   [junit4]   2> 95270 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: apply all deletes during flush
   [junit4]   2> 95270 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: now apply all deletes for all segments maxDoc=400
   [junit4]   2> 95271 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 95271 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: applyDeletes: no segments; skipping
   [junit4]   2> 95272 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-271]: prune sis=segments_1: _0(5.2.0):C31 _1(5.2.0):C369 
minGen=5 packetCount=0
   [junit4]   2> 95273 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: return reader version=5 
reader=StandardDirectoryReader(segments_1:5:nrt _0(5.2.0):C31 _1(5.2.0):C369)
   [junit4]   2> 95275 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-271]: qtp14445605-271 finishFullFlush success=true
   [junit4]   2> 95275 T271 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-271]: getReader took 5 msec
   [junit4]   2> 95276 T271 x:collection1 C97 oass.SolrIndexSearcher.<init> 
Opening Searcher@cbfcaf[collection1] main
   [junit4]   2> 95276 T271 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 95277 T274 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95278 T274 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 95279 T274 x:collection1 C97 oasc.SolrCore.getSearcher WARN 
[collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
   [junit4]   2> 95284 T274 x:collection1 C97 oasc.SolrCore.openNewSearcher 
SolrIndexSearcher has not changed - not re-opening: 
org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 95285 T274 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 95294 T277 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{add=[T4:0 (1500478621992615936), T4:1 (1500478621996810240), T4:2 
(1500478621996810241), T4:3 (1500478621996810242), T4:4 (1500478621996810243), 
T4:5 (1500478621996810244), T4:6 (1500478621996810245), T4:7 
(1500478621996810246), T4:8 (1500478621996810247), T4:9 (1500478621996810248), 
... (100 adds)]} 0 8
   [junit4]   2> 95314 T273 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95317 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: commit: start
   [junit4]   2> 95317 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: commit: enter lock
   [junit4]   2> 95317 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: commit: now prepare
   [junit4]   2> 95318 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: prepareCommit: flush
   [junit4]   2> 95319 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]:   index before flush _0(5.2.0):C31 _1(5.2.0):C369
   [junit4]   2> 95319 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-273]: startFullFlush
   [junit4]   2> 95319 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-273]: anyChanges? numDocsInRam=100 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 95319 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWFC][qtp14445605-273]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0 99 deleted terms (unique count=99) bytesUsed=13950, 
segment=_2, aborted=false, numDocsInRAM=100, deleteQueue=DWDQ: [ generation: 4 
]]
   [junit4]   2> 95320 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-273]: flush postings as segment _2 numDocs=100
   [junit4]   2> 95320 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-273]: new segment has 0 deleted docs
   [junit4]   2> 95323 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-273]: new segment has no vectors; norms; no docValues; prox; 
freqs
   [junit4]   2> 95323 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-273]: flushedFiles=[_2.nvd, _2.fdt, _2.nvm, 
_2_Lucene50_0.pos, _2.fnm, _2.fdx, _2_Lucene50_0.doc, _2_Lucene50_0.tim, 
_2_Lucene50_0.tip]
   [junit4]   2> 95324 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-273]: flushed codec=Lucene50
   [junit4]   2> 95324 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DWPT][qtp14445605-273]: flushed: segment=_2 ramUsed=0.114 MB 
newFlushedSize=0.004 MB docs/MB=26,941.829
   [junit4]   2> 95324 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-273]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 95324 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: publishFlushedSegment
   [junit4]   2> 95325 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-273]: push deletes  100 deleted terms (unique count=100) 
bytesUsed=1056 segmentPrivate?=false delGen=9 packetCount=1 totBytesUsed=1056
   [junit4]   2> 95325 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: publish sets newSegment delGen=10 seg=_2(5.2.0):C100
   [junit4]   2> 95325 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-273]: now checkpoint "_0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100" [3 segments ; isCommit = false]
   [junit4]   2> 95326 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-273]: 0 msec to checkpoint
   [junit4]   2> 95326 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: apply all deletes during flush
   [junit4]   2> 95326 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: now apply all deletes for all segments maxDoc=500
   [junit4]   2> 95326 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-273]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 95326 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-273]: applyDeletes: infos=[_0(5.2.0):C31, _1(5.2.0):C369, 
_2(5.2.0):C100] packetCount=1
   [junit4]   2> 95327 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-273]: applyTermDeletes took 0.2 msec for 3 segments and 1 
packets; 100 del terms visited; 201 seg terms visited
   [junit4]   2> 95327 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-273]: applyDeletes: 0 new deleted documents
   [junit4]   2> 95328 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-273]: applyDeletes took 2 msec for 3 segments, 0 newly deleted 
docs (query deletes), 100 visited terms, allDeleted=null
   [junit4]   2> 95328 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-273]: prune sis=segments_1: _0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100 minGen=11 packetCount=1
   [junit4]   2> 95328 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-273]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 95328 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-273]: qtp14445605-273 finishFullFlush success=true
   [junit4]   2> 95328 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-273]: findMerges: 3 segments
   [junit4]   2> 95330 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-273]:   seg=_1(5.2.0):C369 size=0.010 MB [floored]
   [junit4]   2> 95330 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-273]:   seg=_2(5.2.0):C100 size=0.004 MB [floored]
   [junit4]   2> 95330 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-273]:   seg=_0(5.2.0):C31 size=0.002 MB [floored]
   [junit4]   2> 95330 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-273]:   allowedSegmentCount=1 vs count=3 (eligible count=3) 
tooBigCount=0
   [junit4]   2> 95332 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[MS][qtp14445605-273]: now merge
   [junit4]   2> 95332 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[MS][qtp14445605-273]:   index: _0(5.2.0):C31 _1(5.2.0):C369 _2(5.2.0):C100
   [junit4]   2> 95332 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[MS][qtp14445605-273]:   no more merges pending; now return
   [junit4]   2> 95333 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: startCommit(): start
   [junit4]   2> 95333 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: startCommit index=_0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100 changeCount=8
   [junit4]   2> 95333 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: done all syncs: [_0_Lucene50_0.doc, _1.si, _1.nvd, 
_0.nvd, _2.fdt, _2_Lucene50_0.pos, _1.nvm, _1.fnm, _2.fdx, _2_Lucene50_0.tim, 
_2_Lucene50_0.tip, _1_Lucene50_0.tim, _2.nvd, _1_Lucene50_0.pos, _2.nvm, 
_2.fnm, _1.fdx, _1.fdt, _1_Lucene50_0.tip, _0.si, _0_Lucene50_0.tim, _2.si, 
_0_Lucene50_0.pos, _0.fdx, _0_Lucene50_0.tip, _2_Lucene50_0.doc, _0.fdt, 
_1_Lucene50_0.doc, _0.nvm, _0.fnm]
   [junit4]   2> 95334 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: commit: pendingCommit != null
   [junit4]   2> 95334 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-273]: now checkpoint "_0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100" [3 segments ; isCommit = true]
   [junit4]   2> 95334 T273 x:collection1 C97 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@9666b5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f33b45),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory@9666b5 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f33b45),segFN=segments_3,generation=3}
   [junit4]   2> 95334 T273 x:collection1 C97 
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 95335 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-273]: deleteCommits: now decRef commit "segments_2"
   [junit4]   2> 95335 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-273]: delete "segments_2"
   [junit4]   2> 95337 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IFD][qtp14445605-273]: 2 msec to checkpoint
   [junit4]   2> 95337 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: commit: wrote segments file "segments_3"
   [junit4]   2> 95338 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: commit: took 16.9 msec
   [junit4]   2> 95338 T273 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-273]: commit: done
   [junit4]   2> 95339 T273 x:collection1 C97 oasc.SolrCore.getSearcher WARN 
[collection1] Error opening new searcher. exceeded limit of 
maxWarmingSearchers=2, try again later.
   [junit4]   2> 95339 T273 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {} 
0 25
   [junit4]   2> 95339 T273 x:collection1 C97 oasc.SolrException.log Ignoring 
exception matching maxWarmingSearchers
   [junit4]   2> 95340 T289 oascs.LargeVolumeTestBase$DocThread.run Caught 
benign exception during commit: Error from server at 
http://127.0.0.1:57064/solr/collection1: Error opening new searcher. exceeded 
limit of maxWarmingSearchers=2, try again later.
   [junit4]   2> 95215 T272 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{add=[T2:0 (1500478621912924160), T2:1 (1500478621915021312), T2:2 
(1500478621915021313), T2:3 (1500478621915021314), T2:4 (1500478621915021315), 
T2:5 (1500478621915021316), T2:6 (1500478621915021317), T2:7 
(1500478621915021318), T2:8 (1500478621915021319), T2:9 (1500478621915021320), 
... (100 adds)]} 0 5
   [junit4]   2> 95207 T276 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} 
{add=[T3:0 (1500478621887758336), T3:1 (1500478621902438420), T3:2 
(1500478621903486977), T3:3 (1500478621903486979), T3:4 (1500478621903486981), 
T3:5 (1500478621903486983), T3:6 (1500478621903486985), T3:7 
(1500478621903486987), T3:8 (1500478621903486989), T3:9 (1500478621903486991), 
... (100 adds)]} 0 26
   [junit4]   2> 95371 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@cbfcaf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C31)
 Uninverting(_1(5.2.0):C369)))}
   [junit4]   2> 95371 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 95372 T281 x:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@cbfcaf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C31)
 Uninverting(_1(5.2.0):C369)))}
   [junit4]   2> 95373 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@cbfcaf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C31)
 Uninverting(_1(5.2.0):C369)))}
   [junit4]   2> 95373 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 95418 T274 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 176
   [junit4]   2> 95420 T277 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95420 T277 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 95421 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-277]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 95421 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-277]: flush at getReader
   [junit4]   2> 95422 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-277]: startFullFlush
   [junit4]   2> 95423 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-277]: apply all deletes during flush
   [junit4]   2> 95423 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-277]: now apply all deletes for all segments maxDoc=500
   [junit4]   2> 95423 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-277]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 95424 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-277]: applyDeletes: no segments; skipping
   [junit4]   2> 95424 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[BD][qtp14445605-277]: prune sis=segments_1: _0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100 minGen=11 packetCount=0
   [junit4]   2> 95426 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-277]: return reader version=7 
reader=StandardDirectoryReader(segments_1:7:nrt _0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100)
   [junit4]   2> 95426 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[DW][qtp14445605-277]: qtp14445605-277 finishFullFlush success=true
   [junit4]   2> 95427 T277 x:collection1 C97 oasu.LoggingInfoStream.message 
[IW][qtp14445605-277]: getReader took 6 msec
   [junit4]   2> 95427 T277 x:collection1 C97 oass.SolrIndexSearcher.<init> 
Opening Searcher@1770b5d[collection1] main
   [junit4]   2> 95428 T277 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 95428 T271 x:collection1 C97 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 198
   [junit4]   2> 95429 T273 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95429 T273 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 95429 T273 x:collection1 C97 oasc.SolrCore.getSearcher WARN 
[collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
   [junit4]   2> 95430 T273 x:collection1 C97 oasc.SolrCore.openNewSearcher 
SolrIndexSearcher has not changed - not re-opening: 
org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 95431 T273 x:collection1 C97 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 95433 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1770b5d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C31)
 Uninverting(_1(5.2.0):C369) Uninverting(_2(5.2.0):C100)))}
   [junit4]   2> 95433 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> ASYNC  NEW_CORE C98 name=collection1 
org.apache.solr.core.SolrCore@131466
   [junit4]   2> 95442 T272 x:collection1 C98 oasu.DirectUpdateHandler2.commit 
start 
commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 95442 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: forceMerge: index now _0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100
   [junit4]   2> 95445 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: now flush at forceMerge
   [junit4]   2> 95447 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]:   start flush: applyAllDeletes=true
   [junit4]   2> 95448 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]:   index before flush _0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100
   [junit4]   2> 95448 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[DW][qtp14445605-272]: startFullFlush
   [junit4]   2> 95448 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[DW][qtp14445605-272]: qtp14445605-272 finishFullFlush success=true
   [junit4]   2> 95448 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: apply all deletes during flush
   [junit4]   2> 95448 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: now apply all deletes for all segments maxDoc=500
   [junit4]   2> 95449 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[BD][qtp14445605-272]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 95449 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[BD][qtp14445605-272]: applyDeletes: no segments; skipping
   [junit4]   2> 95449 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[BD][qtp14445605-272]: prune sis=segments_1: _0(5.2.0):C31 _1(5.2.0):C369 
_2(5.2.0):C100 minGen=11 packetCount=0
   [junit4]   2> 95449 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-272]: findForcedMerges maxSegmentCount=1 infos=_0(5.2.0):C31 
_1(5.2.0):C369 _2(5.2.0):C100 segmentsToMerge={_2(5.2.0):C100=true, 
_0(5.2.0):C31=true, _1(5.2.0):C369=true}
   [junit4]   2> 95451 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-272]: eligible=[_1(5.2.0):C369, _2(5.2.0):C100, _0(5.2.0):C31]
   [junit4]   2> 95452 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-272]: forceMergeRunning=false
   [junit4]   2> 95452 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[TMP][qtp14445605-272]: add final merge=_1(5.2.0):C369 _2(5.2.0):C100 
_0(5.2.0):C31
   [junit4]   2> 95452 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: add merge to pendingMerges: _1(5.2.0):C369 
_2(5.2.0):C100 _0(5.2.0):C31 [total 1 pending]
   [junit4]   2> 95452 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: registerMerge merging= []
   [junit4]   2> 95452 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: registerMerge info=_1(5.2.0):C369
   [junit4]   2> 95454 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: registerMerge info=_2(5.2.0):C100
   [junit4]   2> 95454 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: registerMerge info=_0(5.2.0):C31
   [junit4]   2> 95454 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[MS][qtp14445605-272]: now merge
   [junit4]   2> 95454 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[MS][qtp14445605-272]:   index: _0(5.2.0):C31 _1(5.2.0):C369 _2(5.2.0):C100
   [junit4]   2> 95455 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[MS][qtp14445605-272]:   consider merge _1(5.2.0):C369 _2(5.2.0):C100 
_0(5.2.0):C31
   [junit4]   2> 95456 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[MS][qtp14445605-272]:     launch new thread [Lucene Merge Thread #0]
   [junit4]   2> 95458 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[MS][qtp14445605-272]: updateMergeThreads ioThrottle=true targetMBPerSec=20.0 
MB/sec
   [junit4]   2>        merge thread Lucene Merge Thread #0 estSize=0.0 MB 
(written=0.0 MB) runTime=0.0s (stopped=0.0s, paused=0.0s) rate=unlimited
   [junit4]   2>          leave running at Infinity MB/sec
   [junit4]   2> 95459 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[MS][qtp14445605-272]:   no more merges pending; now return
   [junit4]   2> 95461 T281 x:collection1 oasc.SolrCore.registerSearcher 
[collection1] Registered new searcher Searcher@1770b5d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C31)
 Uninverting(_1(5.2.0):C369) Uninverting(_2(5.2.0):C100)))}
   [junit4]   2> 95462 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@1770b5d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C31)
 Uninverting(_1(5.2.0):C369) Uninverting(_2(5.2.0):C100)))}
   [junit4]   2> 95462 T281 x:collection1 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 95462 T273 x:collection1 C98 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 33
   [junit4]   2> 95463 T290 x:collection1 oasu.LoggingInfoStream.message 
[MS][Lucene Merge Thread #0]:   merge thread: start
   [junit4]   2> 95465 T277 x:collection1 C98 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 45
   [junit4]   2> 95470 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: now apply deletes for 3 merging segments
   [junit4]   2> 95470 T290 x:collection1 oasu.LoggingInfoStream.message 
[BD][Lucene Merge Thread #0]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 95470 T290 x:collection1 oasu.LoggingInfoStream.message 
[BD][Lucene Merge Thread #0]: applyDeletes: no segments; skipping
   [junit4]   2> 95470 T290 x:collection1 oasu.LoggingInfoStream.message 
[BD][Lucene Merge Thread #0]: prune sis=segments_1: _0(5.2.0):C31 
_1(5.2.0):C369 _2(5.2.0):C100 minGen=11 packetCount=0
   [junit4]   2> 95470 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: merge seg=_3 _1(5.2.0):C369 _2(5.2.0):C100 
_0(5.2.0):C31
   [junit4]   2> 95471 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: now merge
   [junit4]   2>          merge=_1(5.2.0):C369 _2(5.2.0):C100 _0(5.2.0):C31
   [junit4]   2>          index=_0(5.2.0):C31 _1(5.2.0):C369 _2(5.2.0):C100
   [junit4]   2> 95471 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: merging _1(5.2.0):C369 _2(5.2.0):C100 
_0(5.2.0):C31
   [junit4]   2> 95471 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: seg=_1(5.2.0):C369 no deletes
   [junit4]   2> 95471 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: seg=_2(5.2.0):C100 no deletes
   [junit4]   2> 95471 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: seg=_0(5.2.0):C31 no deletes
   [junit4]   2> 95471 T290 x:collection1 oasu.LoggingInfoStream.message 
[SM][Lucene Merge Thread #0]: merge store matchedCount=3 vs 3
   [junit4]   2> 95473 T290 x:collection1 oasu.LoggingInfoStream.message 
[SM][Lucene Merge Thread #0]: 1 msec to merge stored fields [500 docs]
   [junit4]   2> 95476 T290 x:collection1 oasu.LoggingInfoStream.message 
[SM][Lucene Merge Thread #0]: 2 msec to merge postings [500 docs]
   [junit4]   2> 95476 T290 x:collection1 oasu.LoggingInfoStream.message 
[SM][Lucene Merge Thread #0]: 0 msec to merge doc values [500 docs]
   [junit4]   2> 95477 T290 x:collection1 oasu.LoggingInfoStream.message 
[SM][Lucene Merge Thread #0]: 0 msec to merge norms [500 docs]
   [junit4]   2> 95477 T290 x:collection1 oasu.LoggingInfoStream.message 
[SM][Lucene Merge Thread #0]: 0 msec to write field infos [500 docs]
   [junit4]   2> 95477 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: merge codec=Lucene50 maxDoc=500; merged segment 
has no vectors; norms; no docValues; prox; freqs; 0.0 sec (0.0 sec stopped, 0.0 
sec paused) to merge segment [0.01 MB, 2.80 MB/sec]
   [junit4]   2> 95477 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: merged segment size=0.013 MB vs estimate=0.017 MB
   [junit4]   2> 95477 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: commitMerge: _1(5.2.0):C369 _2(5.2.0):C100 
_0(5.2.0):C31 index=_0(5.2.0):C31 _1(5.2.0):C369 _2(5.2.0):C100
   [junit4]   2> 95477 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: commitMergeDeletes _1(5.2.0):C369 _2(5.2.0):C100 
_0(5.2.0):C31
   [junit4]   2> 95477 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: no new deletes or field updates since merge 
started
   [junit4]   2> 95478 T290 x:collection1 oasu.LoggingInfoStream.message 
[IFD][Lucene Merge Thread #0]: now checkpoint "_3(5.2.0):C500" [1 segments ; 
isCommit = false]
   [junit4]   2> 95478 T290 x:collection1 oasu.LoggingInfoStream.message 
[IFD][Lucene Merge Thread #0]: 0 msec to checkpoint
   [junit4]   2> 95478 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: after commitMerge: _3(5.2.0):C500
   [junit4]   2> 95478 T290 x:collection1 oasu.LoggingInfoStream.message 
[TMP][Lucene Merge Thread #0]: findForcedMerges maxSegmentCount=1 
infos=_3(5.2.0):C500 segmentsToMerge={_2(5.2.0):C100=true, _0(5.2.0):C31=true, 
_1(5.2.0):C369=true, _3(5.2.0):C500=false}
   [junit4]   2> 95478 T290 x:collection1 oasu.LoggingInfoStream.message 
[TMP][Lucene Merge Thread #0]: already merged
   [junit4]   2> 95479 T290 x:collection1 oasu.LoggingInfoStream.message 
[IW][Lucene Merge Thread #0]: merge time 9 msec for 500 docs
   [junit4]   2> 95479 T290 x:collection1 oasu.LoggingInfoStream.message 
[MS][Lucene Merge Thread #0]:   merge thread: done
   [junit4]   2> 95479 T290 x:collection1 oasu.LoggingInfoStream.message 
[MS][Lucene Merge Thread #0]: now merge
   [junit4]   2> 95479 T290 x:collection1 oasu.LoggingInfoStream.message 
[MS][Lucene Merge Thread #0]:   index: _3(5.2.0):C500
   [junit4]   2> 95479 T290 x:collection1 oasu.LoggingInfoStream.message 
[MS][Lucene Merge Thread #0]:   no more merges pending; now return
   [junit4]   2> 95480 T290 x:collection1 oasu.LoggingInfoStream.message 
[MS][Lucene Merge Thread #0]: updateMergeThreads ioThrottle=true 
targetMBPerSec=20.0 MB/sec
   [junit4]   2> 95484 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: commit: start
   [junit4]   2> 95484 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: commit: enter lock
   [junit4]   2> 95484 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: commit: now prepare
   [junit4]   2> 95485 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: prepareCommit: flush
   [junit4]   2> 95485 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]:   index before flush _3(5.2.0):C500
   [junit4]   2> 95485 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[DW][qtp14445605-272]: startFullFlush
   [junit4]   2> 95485 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: apply all deletes during flush
   [junit4]   2> 95486 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[IW][qtp14445605-272]: now apply all deletes for all segments maxDoc=500
   [junit4]   2> 95486 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[BD][qtp14445605-272]: applyDeletes: open segment readers took 0 msec
   [junit4]   2> 95486 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[BD][qtp14445605-272]: applyDeletes: no segments; skipping
   [junit4]   2> 95486 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[BD][qtp14445605-272]: prune sis=segments_1: _3(5.2.0):C500 minGen=11 
packetCount=0
   [junit4]   2> 95486 T272 x:collection1 C98 oasu.LoggingInfoStream.message 
[DW][qtp14445605-272]: qtp14445605-272 finishFullFlush success=true
   [junit4]   2>

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

        at org.apache.solr.update.UpdateLog.ensureLog(UpdateLog.java:852)
   [junit4]   2>        at 
org.apache.solr.update.UpdateLog.add(UpdateLog.java:414)
   [junit4]   2>        at 
org.apache.solr.update.UpdateLog.add(UpdateLog.java:400)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:258)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:163)
   [junit4]   2>        at 
org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:69)
   [junit4]   2>        at 
org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:51)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:955)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1110)
   [junit4]   2>        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:706)
   [junit4]   2>        at 
org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:104)
   [junit4]   2>        at 
org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:250)
   [junit4]   2>        at 
org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:177)
   [junit4]   2>        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:98)
   [junit4]   2>        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2047)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:578)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:378)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:176)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:169)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]   2>        at 
org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
   [junit4]   2>        at 
org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:497)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> NOTE: test params are: codec=Lucene50, 
sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=en_IE, 
timezone=Europe/San_Marino
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_45 
(32-bit)/cpus=3,threads=1,free=25850392,total=66637824
   [junit4]   2> NOTE: All tests run in this JVM: [QueryResponseTest, 
SolrPingTest, SolrSchemalessExampleTest, SolrExampleJettyTest, 
TestNamedListCodec, TestZkConfigManager, LargeVolumeBinaryJettyTest, 
MergeIndexesEmbeddedTest, SolrExceptionTest, SolrExampleBinaryTest, 
ClientUtilsTest, LargeVolumeEmbeddedTest, BasicHttpSolrClientTest, 
SolrExampleStreamingBinaryTest, SolrExampleXMLTest, TestBatchUpdate, 
TestSpellCheckResponse, LargeVolumeJettyTest]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=LargeVolumeJettyTest -Dtests.seed=5AEA969D5ED84A9C -Dtests.slow=true 
-Dtests.locale=en_IE -Dtests.timezone=Europe/San_Marino -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J1 | LargeVolumeJettyTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Some resources were 
not closed, shutdown, or released.
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([5AEA969D5ED84A9C]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:234)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)Throwable #2: 
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 
5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog\tlog.0000000000000000002:
 java.nio.file.FileSystemException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 
5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog\tlog.0000000000000000002:
 The process cannot access the file because it is being used by another process.
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data\tlog
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1\data
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1: 
java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001\collection1
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001\tempDir-001
   [junit4]    >    
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001: java.nio.file.DirectoryNotEmptyException: 
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.embedded.LargeVolumeJettyTest
 5AEA969D5ED84A9C-001
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:294)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [33/57] on J1 in 1.88s, 1 test, 1 failure, 1 error <<< 
FAILURES!

[...truncated 82 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:536: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:484: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39: 
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:242: The 
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:511:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1433:
 The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:991:
 There were test failures: 57 suites, 381 tests, 2 suite-level errors

Total time: 64 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


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

Reply via email to