You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/12/03 17:28:45 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_40-ea-b09) -
Build # 4467 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4467/
Java: 32bit/jdk1.8.0_40-ea-b09 -server -XX:+UseParallelGC (asserts: true)
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.LargeVolumeEmbeddedTest
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog\tlog.0000000000000000003: java.nio.file.FileSystemException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog\tlog.0000000000000000003: The process cannot access the file because it is being used by another process. C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001 C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001 C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog\tlog.0000000000000000003: java.nio.file.FileSystemException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog\tlog.0000000000000000003: The process cannot access the file because it is being used by another process.
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001
at __randomizedtesting.SeedInfo.seed([54CFA8328E33D17]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:281)
at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:170)
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 10664 lines...]
[junit4] Suite: org.apache.solr.client.solrj.embedded.LargeVolumeEmbeddedTest
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001
[junit4] 2> 362401 T750 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (true)
[junit4] 2> 363110 T750 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 363110 T750 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\'
[junit4] 2> 363156 T750 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 363156 T750 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-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\contrib\extraction\lib).
[junit4] 2> 363156 T750 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\dist).
[junit4] 2> 363159 T750 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-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\contrib\clustering\lib).
[junit4] 2> 363159 T750 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\dist).
[junit4] 2> 363159 T750 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-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\contrib\langid\lib).
[junit4] 2> 363159 T750 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\dist).
[junit4] 2> 363159 T750 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-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\contrib\velocity\lib).
[junit4] 2> 363159 T750 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\dist).
[junit4] 2> 363185 T750 oasu.SolrIndexConfig.<init> IndexWriter infoStream solr logging is enabled
[junit4] 2> 363187 T750 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 363216 T750 oasc.SolrConfig.<init> Loaded SolrConfig: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001/collection1/conf/solrconfig.xml
[junit4] 2> 363217 T750 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf\C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001/collection1/conf/schema.xml
[junit4] 2> 363222 T750 oass.IndexSchema.readSchema [null] Schema name=example
[junit4] 2> 363411 T750 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 363412 T750 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 363412 T750 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 363423 T750 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 363426 T750 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 363454 T750 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 363454 T750 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001
[junit4] 2> 363454 T750 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\'
[junit4] 2> 363474 T750 oasc.CoreContainer.<init> New CoreContainer 29115370
[junit4] 2> 363474 T750 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\]
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 363478 T750 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 363478 T750 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=false
[junit4] 2> 363478 T750 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 363478 T750 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 363478 T750 oasc.CoreContainer.load Host Name:
[junit4] 2> 363484 T751 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1/'
[junit4] 2> 363509 T751 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 363511 T751 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-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\contrib\extraction\lib).
[junit4] 2> 363511 T751 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\dist).
[junit4] 2> 363511 T751 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-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\contrib\clustering\lib).
[junit4] 2> 363511 T751 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\dist).
[junit4] 2> 363511 T751 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-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\contrib\langid\lib).
[junit4] 2> 363511 T751 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\dist).
[junit4] 2> 363513 T751 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-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\contrib\velocity\lib).
[junit4] 2> 363513 T751 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\..\..\..\dist).
[junit4] 2> 363538 T751 oasu.SolrIndexConfig.<init> IndexWriter infoStream solr logging is enabled
[junit4] 2> 363540 T751 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 363567 T751 oasc.SolrConfig.<init> Loaded SolrConfig: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001/collection1/conf/solrconfig.xml
[junit4] 2> 363568 T751 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1/conf\C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001/collection1/conf/schema.xml
[junit4] 2> 363573 T751 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 363744 T751 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 363745 T751 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 363745 T751 oasr.RestManager$Registry.registerManagedResource Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 363761 T751 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 363764 T751 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 363794 T751 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1/
[junit4] 2> 363794 T751 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 363795 T751 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1/, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\
[junit4] 2> 363795 T751 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ab70f0
[junit4] 2> 363796 T751 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 363796 T751 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 2> 363797 T751 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\
[junit4] 2> 363797 T751 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\index/
[junit4] 2> 363797 T751 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\index' doesn't exist. Creating new index...
[junit4] 2> 363798 T751 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\index
[junit4] 2> 363798 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: init: current segments file is "segments"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1a26e4b
[junit4] 2> 363799 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 363799 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: 0 msec to checkpoint
[junit4] 2> 363799 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: init: create=true
[junit4] 2> 363799 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0)
[junit4] 2> index=
[junit4] 2> version=6.0.0
[junit4] 2> analyzer=null
[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=2, mergeThreadPriority=-1
[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@118c2d9
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> writer=org.apache.lucene.util.SetOnce@1731b24
[junit4] 2>
[junit4] 2> 363799 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: now flush at close
[junit4] 2> 363799 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 363800 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: index before flush
[junit4] 2> 363800 T751 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-461-thread-1]: startFullFlush
[junit4] 2> 363801 T751 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-461-thread-1]: coreLoadExecutor-461-thread-1 finishFullFlush success=true
[junit4] 2> 363801 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: apply all deletes during flush
[junit4] 2> 363801 T751 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-461-thread-1]: prune sis=segments: minGen=9223372036854775807 packetCount=0
[junit4] 2> 363801 T751 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-461-thread-1]: now merge
[junit4] 2> 363801 T751 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-461-thread-1]: index:
[junit4] 2> 363801 T751 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-461-thread-1]: no more merges pending; now return
[junit4] 2> 363801 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: waitForMerges
[junit4] 2> 363802 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: waitForMerges done
[junit4] 2> 363802 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: commit: start
[junit4] 2> 363802 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: commit: enter lock
[junit4] 2> 363802 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: commit: now prepare
[junit4] 2> 363803 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: prepareCommit: flush
[junit4] 2> 363803 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: index before flush
[junit4] 2> 363803 T751 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-461-thread-1]: startFullFlush
[junit4] 2> 363803 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: apply all deletes during flush
[junit4] 2> 363804 T751 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-461-thread-1]: prune sis=segments: minGen=9223372036854775807 packetCount=0
[junit4] 2> 363804 T751 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-461-thread-1]: coreLoadExecutor-461-thread-1 finishFullFlush success=true
[junit4] 2> 363804 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: startCommit(): start
[junit4] 2> 363804 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: startCommit index= changeCount=1
[junit4] 2> 363804 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: done all syncs: []
[junit4] 2> 363804 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: commit: pendingCommit != null
[junit4] 2> 363805 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 363805 T751 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0),segFN=segments_1,generation=1}
[junit4] 2> 363805 T751 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 363806 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: 0 msec to checkpoint
[junit4] 2> 363806 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: commit: wrote segments file "segments_1"
[junit4] 2> 363806 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: commit: took 3.2 msec
[junit4] 2> 363806 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: commit: done
[junit4] 2> 363806 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: rollback
[junit4] 2> 363806 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: all running merges have aborted
[junit4] 2> 363806 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: rollback: done finish merges
[junit4] 2> 363807 T751 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-461-thread-1]: abort
[junit4] 2> 363807 T751 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-461-thread-1]: done abort; abortedFiles=[] success=true
[junit4] 2> 363807 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: rollback: infos=
[junit4] 2> 363808 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 363808 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: 0 msec to checkpoint
[junit4] 2> 363809 T751 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4] 2> 363809 T751 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 363809 T751 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4] 2> 363810 T751 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4] 2> 363810 T751 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4] 2> 363814 T751 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 363814 T751 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 363815 T751 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 363815 T751 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 363816 T751 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 363816 T751 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 363817 T751 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 363817 T751 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 363817 T751 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 363818 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 363819 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 363819 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 363819 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 363820 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 363820 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 363820 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 363821 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 363822 T751 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 363822 T751 oasc.RequestHandlers.initHandlersFromConfig created /select: solr.SearchHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig created /query: solr.SearchHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig created /export: solr.SearchHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig created /browse: solr.SearchHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 363823 T751 oasc.RequestHandlers.initHandlersFromConfig created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 363826 T751 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 363826 T751 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 363826 T751 oasc.RequestHandlers.initHandlersFromConfig created /spell: solr.SearchHandler
[junit4] 2> 363826 T751 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 363826 T751 oasc.RequestHandlers.initHandlersFromConfig created /tvrh: solr.SearchHandler
[junit4] 2> 363826 T751 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 363827 T751 oasc.RequestHandlers.initHandlersFromConfig created /terms: solr.SearchHandler
[junit4] 2> 363827 T751 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.SearchHandler
[junit4] 2> 363827 T751 oasc.RequestHandlers.initHandlersFromConfig created /elevate: solr.SearchHandler
[junit4] 2> 363833 T751 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 363835 T751 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 363837 T751 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 363838 T751 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 363844 T751 oasu.CommitTracker.<init> Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 363844 T751 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 363845 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1a26e4b
[junit4] 2> 363845 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: init: load commit "segments_1"
[junit4] 2> 363845 T751 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0),segFN=segments_1,generation=1}
[junit4] 2> 363845 T751 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 363845 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 363846 T751 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-461-thread-1]: 0 msec to checkpoint
[junit4] 2> 363846 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: init: create=false
[junit4] 2> 363846 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0)
[junit4] 2> index=
[junit4] 2> version=6.0.0
[junit4] 2> analyzer=null
[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=2, mergeThreadPriority=-1
[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@19a1e1a
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> writer=org.apache.lucene.util.SetOnce@18a6bff
[junit4] 2>
[junit4] 2> 363847 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: flush at getReader
[junit4] 2> 363847 T751 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-461-thread-1]: startFullFlush
[junit4] 2> 363847 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: apply all deletes during flush
[junit4] 2> 363847 T751 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-461-thread-1]: prune sis=segments_1: minGen=9223372036854775807 packetCount=0
[junit4] 2> 363847 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: return reader version=1 reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 363847 T751 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-461-thread-1]: coreLoadExecutor-461-thread-1 finishFullFlush success=true
[junit4] 2> 363847 T751 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-461-thread-1]: getReader took 0 msec
[junit4] 2> 363847 T751 oass.SolrIndexSearcher.<init> Opening Searcher@1d879ff[collection1] main
[junit4] 2> 363849 T751 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf
[junit4] 2> 363849 T751 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf}
[junit4] 2> 363849 T751 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf
[junit4] 2> 363849 T751 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf
[junit4] 2> 363849 T751 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 363849 T751 oasr.RestManager.init Initializing 2 registered ManagedResources
[junit4] 2> 363849 T751 oasr.ManagedResourceStorage.load Reading _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf
[junit4] 2> 363851 T751 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf
[junit4] 2> 363851 T751 oasr.ManagedResource.reloadFromStorage Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
[junit4] 2> 363851 T751 oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 35 words for /schema/analysis/stopwords/english
[junit4] 2> 363851 T751 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 363852 T751 oasr.ManagedResourceStorage.load Reading _schema_analysis_synonyms_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf
[junit4] 2> 363852 T751 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf
[junit4] 2> 363853 T751 oasr.ManagedResource.reloadFromStorage Loaded initArgs {ignoreCase=true,format=solr} for /schema/analysis/synonyms/english
[junit4] 2> 363853 T751 oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage Loaded 3 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 363853 T751 oasr.ManagedResource.notifyObserversDuringInit Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 363854 T751 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 363854 T751 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> 363854 T751 oashc.SpellCheckComponent.inform No queryConverter defined, using default converter
[junit4] 2> 363855 T751 oashc.QueryElevationComponent.inform Loading QueryElevation from: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\tempDir-001\collection1\conf\elevate.xml
[junit4] 2> 363863 T751 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 363865 T752 oasc.QuerySenderListener.newSearcher QuerySenderListener sending requests to Searcher@1d879ff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 363865 T751 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> ASYNC NEW_CORE C98 name=collection1 org.apache.solr.core.SolrCore@2712c
[junit4] 2> 363865 T752 C98 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=0
[junit4] 2> 363865 T752 oasc.QuerySenderListener.newSearcher QuerySenderListener done.
[junit4] 2> 363865 T752 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 363865 T752 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 363866 T752 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d879ff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 363869 T750 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 363871 T750 oas.SolrTestCaseJ4.setUp ###Starting testMultiThreaded
[junit4] 2> 363876 T750 C98 oasu.LoggingInfoStream.message [DW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 363876 T750 C98 oasu.LoggingInfoStream.message [DW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 363876 T750 C98 oasu.LoggingInfoStream.message [IW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: nrtIsCurrent: infoVersion matches: true; DW changes: true; BD changes: false
[junit4] 2> 363876 T750 C98 oasu.LoggingInfoStream.message [IW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: flush at getReader
[junit4] 2> 363876 T750 C98 oasu.LoggingInfoStream.message [DW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: startFullFlush
[junit4] 2> 363876 T750 C98 oasu.LoggingInfoStream.message [DW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 363876 T750 C98 oasu.LoggingInfoStream.message [DW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]: flush naked frozen global deletes
[junit4] 2> 363878 T750 C98 oasu.LoggingInfoStream.message [BD][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: push deletes 1 deleted queries bytesUsed=64 delGen=2 packetCount=1 totBytesUsed=64
[junit4] 2> 363878 T750 C98 oasu.LoggingInfoStream.message [DW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: flush: push buffered updates: 1 deleted queries bytesUsed=64
[junit4] 2> 363878 T750 C98 oasu.LoggingInfoStream.message [IW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: apply all deletes during flush
[junit4] 2> 363878 T750 C98 oasu.LoggingInfoStream.message [BD][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: prune sis=segments_1: minGen=9223372036854775807 packetCount=1
[junit4] 2> 363878 T750 C98 oasu.LoggingInfoStream.message [BD][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 363878 T750 C98 oasu.LoggingInfoStream.message [IW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: return reader version=1 reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 363878 T750 C98 oasu.LoggingInfoStream.message [DW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17] finishFullFlush success=true
[junit4] 2> 363878 T750 C98 oasu.LoggingInfoStream.message [IW][TEST-LargeVolumeEmbeddedTest.testMultiThreaded-seed#[54CFA8328E33D17]]: getReader took 2 msec
[junit4] 2> 363881 T750 C98 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {deleteByQuery=*:* (-1486486572625821696)} 0 10
[junit4] 2> 363882 T750 oascs.LargeVolumeTestBase.testMultiThreaded Started thread: 0
[junit4] 2> 363882 T750 oascs.LargeVolumeTestBase.testMultiThreaded Started thread: 1
[junit4] 2> 363884 T750 oascs.LargeVolumeTestBase.testMultiThreaded Started thread: 2
[junit4] 2> 363888 T750 oascs.LargeVolumeTestBase.testMultiThreaded Started thread: 3
[junit4] 2> 363888 T750 oascs.LargeVolumeTestBase.testMultiThreaded Started thread: 4
[junit4] 2> 363916 T755 C98 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[T0:0 (1486486572636307456), T0:1 (1486486572639453185), T0:2 (1486486572644696064), T0:3 (1486486572644696065), T0:4 (1486486572644696066), T0:5 (1486486572644696068), T0:6 (1486486572645744640), T0:7 (1486486572645744642), T0:8 (1486486572645744644), T0:9 (1486486572645744646), ... (100 adds)]} 0 34
[junit4] 2> 363916 T755 C98 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 363916 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: commit: start
[junit4] 2> 363916 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: commit: enter lock
[junit4] 2> 363916 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: commit: now prepare
[junit4] 2> 363916 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: prepareCommit: flush
[junit4] 2> 363917 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: index before flush
[junit4] 2> 363918 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: startFullFlush
[junit4] 2> 363924 T759 C98 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[T4:0 (1486486572643647497), T4:1 (1486486572646793229), T4:2 (1486486572646793231), T4:3 (1486486572646793234), T4:4 (1486486572646793236), T4:5 (1486486572646793239), T4:6 (1486486572647841793), T4:7 (1486486572647841796), T4:8 (1486486572647841798), T4:9 (1486486572647841800), ... (100 adds)]} 0 35
[junit4] 2> 363924 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: anyChanges? numDocsInRam=420 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 363924 T755 C98 oasu.LoggingInfoStream.message [DWFC][DocThread-0]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 342 deleted terms (unique count=342) bytesUsed=48173, segment=_0, aborting=false, numDocsInRAM=99, deleteQueue=DWDQ: [ generation: 2 ]]
[junit4] 2> 363925 T755 C98 oasu.LoggingInfoStream.message [DWFC][DocThread-0]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 235 deleted terms (unique count=235) bytesUsed=33107, segment=_1, aborting=false, numDocsInRAM=90, deleteQueue=DWDQ: [ generation: 2 ]]
[junit4] 2> 363925 T755 C98 oasu.LoggingInfoStream.message [DWFC][DocThread-0]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 404 deleted terms (unique count=404) bytesUsed=56929, segment=_2, aborting=false, numDocsInRAM=204, deleteQueue=DWDQ: [ generation: 2 ]]
[junit4] 2> 363925 T755 C98 oasu.LoggingInfoStream.message [DWFC][DocThread-0]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 41 deleted terms (unique count=41) bytesUsed=5772, segment=_3, aborting=false, numDocsInRAM=27, deleteQueue=DWDQ: [ generation: 2 ]]
[junit4] 2> 363932 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flush postings as segment _0 numDocs=99
[junit4] 2> 363977 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has 0 deleted docs
[junit4] 2> 363977 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 363977 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: 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> 363977 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed codec=Lucene50
[junit4] 2> 363979 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed: segment=_0 ramUsed=0.157 MB newFlushedSize(includes docstores)=0.004 MB docs/MB=25,129.272
[junit4] 2> 363979 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flush postings as segment _1 numDocs=90
[junit4] 2> 363982 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has 0 deleted docs
[junit4] 2> 363982 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 363982 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: 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> 363982 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed codec=Lucene50
[junit4] 2> 363982 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed: segment=_1 ramUsed=0.153 MB newFlushedSize(includes docstores)=0.004 MB docs/MB=23,753.295
[junit4] 2> 363982 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flush postings as segment _2 numDocs=204
[junit4] 2> 363990 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has 0 deleted docs
[junit4] 2> 363990 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 363990 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: 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> 363990 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed codec=Lucene50
[junit4] 2> 363990 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed: segment=_2 ramUsed=0.162 MB newFlushedSize(includes docstores)=0.006 MB docs/MB=31,955.408
[junit4] 2> 363990 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flush postings as segment _3 numDocs=27
[junit4] 2> 363994 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has 0 deleted docs
[junit4] 2> 363994 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 363994 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushedFiles=[_3.fdx, _3.nvd, _3_Lucene50_0.pos, _3_Lucene50_0.doc, _3_Lucene50_0.tim, _3.nvm, _3.fnm, _3.fdt, _3_Lucene50_0.tip]
[junit4] 2> 363994 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed codec=Lucene50
[junit4] 2> 363994 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed: segment=_3 ramUsed=0.148 MB newFlushedSize(includes docstores)=0.002 MB docs/MB=13,149.815
[junit4] 2> 363994 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: publishFlushedSegment seg-private updates=null
[junit4] 2> 363995 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publishFlushedSegment
[junit4] 2> 363996 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: push deletes 420 deleted terms (unique count=420) bytesUsed=2080 delGen=4 packetCount=1 totBytesUsed=2080
[junit4] 2> 363996 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publish sets newSegment delGen=5 seg=_0(6.0.0):C99
[junit4] 2> 363996 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: now checkpoint "_0(6.0.0):C99" [1 segments ; isCommit = false]
[junit4] 2> 363997 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: 0 msec to checkpoint
[junit4] 2> 363997 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: publishFlushedSegment seg-private updates=null
[junit4] 2> 363997 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publishFlushedSegment
[junit4] 2> 363997 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publish sets newSegment delGen=6 seg=_1(6.0.0):C90
[junit4] 2> 363998 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90" [2 segments ; isCommit = false]
[junit4] 2> 363998 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: 0 msec to checkpoint
[junit4] 2> 363998 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: publishFlushedSegment seg-private updates=null
[junit4] 2> 363998 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publishFlushedSegment
[junit4] 2> 363998 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publish sets newSegment delGen=7 seg=_2(6.0.0):C204
[junit4] 2> 363999 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204" [3 segments ; isCommit = false]
[junit4] 2> 363999 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: 0 msec to checkpoint
[junit4] 2> 364000 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: publishFlushedSegment seg-private updates=null
[junit4] 2> 364000 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publishFlushedSegment
[junit4] 2> 364000 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publish sets newSegment delGen=8 seg=_3(6.0.0):C27
[junit4] 2> 364001 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27" [4 segments ; isCommit = false]
[junit4] 2> 364001 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: 0 msec to checkpoint
[junit4] 2> 364001 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: apply all deletes during flush
[junit4] 2> 364001 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: applyDeletes: infos=[_0(6.0.0):C99, _1(6.0.0):C90, _2(6.0.0):C204, _3(6.0.0):C27] packetCount=1
[junit4] 2> 364001 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: applyDeletes took 0 msec
[junit4] 2> 364001 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: prune sis=segments_1: _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 minGen=9 packetCount=1
[junit4] 2> 364002 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 364002 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: DocThread-0 finishFullFlush success=true
[junit4] 2> 364002 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: findMerges: 4 segments
[junit4] 2> 364002 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_2(6.0.0):C204 size=0.006 MB [floored]
[junit4] 2> 364002 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_0(6.0.0):C99 size=0.004 MB [floored]
[junit4] 2> 364002 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_1(6.0.0):C90 size=0.004 MB [floored]
[junit4] 2> 364002 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_3(6.0.0):C27 size=0.002 MB [floored]
[junit4] 2> 364004 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: allowedSegmentCount=1 vs count=4 (eligible count=4) tooBigCount=0
[junit4] 2> 364004 T755 C98 oasu.LoggingInfoStream.message [CMS][DocThread-0]: now merge
[junit4] 2> 364005 T755 C98 oasu.LoggingInfoStream.message [CMS][DocThread-0]: index: _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27
[junit4] 2> 364005 T755 C98 oasu.LoggingInfoStream.message [CMS][DocThread-0]: no more merges pending; now return
[junit4] 2> 364005 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: startCommit(): start
[junit4] 2> 364005 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: startCommit index=_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 changeCount=9
[junit4] 2> 364006 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: done all syncs: [_3.fdx, _3.si, _0_Lucene50_0.doc, _1.si, _3_Lucene50_0.pos, _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, _3_Lucene50_0.doc, _2.nvm, _2.fnm, _1.fdx, _1.fdt, _1_Lucene50_0.tip, _0.si, _0_Lucene50_0.tim, _2.si, _3.nvd, _0_Lucene50_0.pos, _0.fdx, _3.nvm, _3.fnm, _0_Lucene50_0.tip, _2_Lucene50_0.doc, _0.fdt, _1_Lucene50_0.doc, _3_Lucene50_0.tim, _3.fdt, _3_Lucene50_0.tip, _0.nvm, _0.fnm]
[junit4] 2> 364006 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: commit: pendingCommit != null
[junit4] 2> 364007 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27" [4 segments ; isCommit = true]
[junit4] 2> 364007 T755 C98 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0),segFN=segments_2,generation=2}
[junit4] 2> 364010 T755 C98 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 364010 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 364010 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: delete "segments_1"
[junit4] 2> 364011 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: 3 msec to checkpoint
[junit4] 2> 364011 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: commit: wrote segments file "segments_2"
[junit4] 2> 364011 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: commit: took 95.2 msec
[junit4] 2> 364011 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: commit: done
[junit4] 2> 364017 T757 C98 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[T2:0 (1486486572640501762), T2:1 (1486486572641550336), T2:2 (1486486572642598912), T2:3 (1486486572642598913), T2:4 (1486486572642598914), T2:5 (1486486572642598915), T2:6 (1486486572642598916), T2:7 (1486486572642598917), T2:8 (1486486572642598918), T2:9 (1486486572642598919), ... (100 adds)]} 0 131
[junit4] 2> 364019 T758 C98 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[T3:0 (1486486572644696067), T3:1 (1486486572647841804), T3:2 (1486486572648890369), T3:3 (1486486572648890371), T3:4 (1486486572648890374), T3:5 (1486486572648890376), T3:6 (1486486572648890379), T3:7 (1486486572648890381), T3:8 (1486486572648890384), T3:9 (1486486572648890386), ... (100 adds)]} 0 129
[junit4] 2> 364020 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: anyChanges? numDocsInRam=68 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 364020 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: nrtIsCurrent: infoVersion matches: false; DW changes: true; BD changes: false
[junit4] 2> 364020 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: flush at getReader
[junit4] 2> 364020 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: startFullFlush
[junit4] 2> 364020 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: anyChanges? numDocsInRam=68 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 364020 T755 C98 oasu.LoggingInfoStream.message [DWFC][DocThread-0]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 45 deleted terms (unique count=45) bytesUsed=6345, segment=_5, aborting=false, numDocsInRAM=46, deleteQueue=DWDQ: [ generation: 3 ]]
[junit4] 2> 364020 T755 C98 oasu.LoggingInfoStream.message [DWFC][DocThread-0]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 21 deleted terms (unique count=21) bytesUsed=2961, segment=_4, aborting=false, numDocsInRAM=22, deleteQueue=DWDQ: [ generation: 3 ]]
[junit4] 2> 364020 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flush postings as segment _5 numDocs=46
[junit4] 2> 364023 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has 0 deleted docs
[junit4] 2> 364023 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 364023 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushedFiles=[_5.nvm, _5.fnm, _5_Lucene50_0.pos, _5.fdt, _5.nvd, _5_Lucene50_0.tip, _5_Lucene50_0.doc, _5_Lucene50_0.tim, _5.fdx]
[junit4] 2> 364023 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed codec=Lucene50
[junit4] 2> 364024 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed: segment=_5 ramUsed=0.107 MB newFlushedSize(includes docstores)=0.002 MB docs/MB=18,537.47
[junit4] 2> 364024 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flush postings as segment _4 numDocs=22
[junit4] 2> 364024 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has 0 deleted docs
[junit4] 2> 364024 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 364025 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushedFiles=[_4.fdt, _4.nvm, _4_Lucene50_0.pos, _4.fnm, _4_Lucene50_0.tip, _4.nvd, _4.fdx, _4_Lucene50_0.doc, _4_Lucene50_0.tim]
[junit4] 2> 364026 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed codec=Lucene50
[junit4] 2> 364026 T755 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-0]: flushed: segment=_4 ramUsed=0.099 MB newFlushedSize(includes docstores)=0.002 MB docs/MB=10,586.816
[junit4] 2> 364026 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: publishFlushedSegment seg-private updates=null
[junit4] 2> 364026 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publishFlushedSegment
[junit4] 2> 364026 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: push deletes 68 deleted terms (unique count=68) bytesUsed=1056 delGen=10 packetCount=1 totBytesUsed=1056
[junit4] 2> 364026 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publish sets newSegment delGen=11 seg=_5(6.0.0):C46
[junit4] 2> 364027 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46" [5 segments ; isCommit = false]
[junit4] 2> 364028 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: 1 msec to checkpoint
[junit4] 2> 364029 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: publishFlushedSegment seg-private updates=null
[junit4] 2> 364029 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publishFlushedSegment
[junit4] 2> 364029 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: publish sets newSegment delGen=12 seg=_4(6.0.0):C22
[junit4] 2> 364030 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22" [6 segments ; isCommit = false]
[junit4] 2> 364030 T755 C98 oasu.LoggingInfoStream.message [IFD][DocThread-0]: 0 msec to checkpoint
[junit4] 2> 364030 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: apply all deletes during flush
[junit4] 2> 364030 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: applyDeletes: infos=[_0(6.0.0):C99, _1(6.0.0):C90, _2(6.0.0):C204, _3(6.0.0):C27, _5(6.0.0):C46, _4(6.0.0):C22] packetCount=1
[junit4] 2> 364035 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: seg=_3(6.0.0):C27 segGen=9 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364035 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: seg=_2(6.0.0):C204 segGen=9 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364035 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: seg=_1(6.0.0):C90 segGen=9 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364044 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: seg=_0(6.0.0):C99 segGen=9 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364044 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: applyDeletes took 14 msec
[junit4] 2> 364044 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: prune sis=segments_1: _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22 minGen=13 packetCount=1
[junit4] 2> 364044 T755 C98 oasu.LoggingInfoStream.message [BD][DocThread-0]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 364044 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: return reader version=13 reader=StandardDirectoryReader(segments_1:13:nrt _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22)
[junit4] 2> 364044 T755 C98 oasu.LoggingInfoStream.message [DW][DocThread-0]: DocThread-0 finishFullFlush success=true
[junit4] 2> 364044 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: findMerges: 6 segments
[junit4] 2> 364046 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_2(6.0.0):C204 size=0.006 MB [floored]
[junit4] 2> 364046 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_0(6.0.0):C99 size=0.004 MB [floored]
[junit4] 2> 364046 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_1(6.0.0):C90 size=0.004 MB [floored]
[junit4] 2> 364048 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_5(6.0.0):C46 size=0.002 MB [floored]
[junit4] 2> 364048 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_4(6.0.0):C22 size=0.002 MB [floored]
[junit4] 2> 364048 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: seg=_3(6.0.0):C27 size=0.002 MB [floored]
[junit4] 2> 364048 T755 C98 oasu.LoggingInfoStream.message [TMP][DocThread-0]: allowedSegmentCount=1 vs count=6 (eligible count=6) tooBigCount=0
[junit4] 2> 364048 T755 C98 oasu.LoggingInfoStream.message [CMS][DocThread-0]: now merge
[junit4] 2> 364048 T755 C98 oasu.LoggingInfoStream.message [CMS][DocThread-0]: index: _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22
[junit4] 2> 364048 T755 C98 oasu.LoggingInfoStream.message [CMS][DocThread-0]: no more merges pending; now return
[junit4] 2> 364050 T755 C98 oasu.LoggingInfoStream.message [IW][DocThread-0]: getReader took 30 msec
[junit4] 2> 364052 T755 C98 oass.SolrIndexSearcher.<init> Opening Searcher@1bb496e[collection1] main
[junit4] 2> 364052 T755 C98 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 364052 T759 C98 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 364052 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: commit: start
[junit4] 2> 364052 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: commit: enter lock
[junit4] 2> 364052 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: commit: now prepare
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: prepareCommit: flush
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: index before flush _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [DW][DocThread-4]: startFullFlush
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: apply all deletes during flush
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [BD][DocThread-4]: applyDeletes: no deletes; skipping
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [BD][DocThread-4]: prune sis=segments_1: _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22 minGen=13 packetCount=0
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [DW][DocThread-4]: DocThread-4 finishFullFlush success=true
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: startCommit(): start
[junit4] 2> 364053 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: startCommit index=_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22 changeCount=14
[junit4] 2> 364056 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: done all syncs: [_3.si, _1.nvd, _0.nvd, _2_Lucene50_0.pos, _1.nvm, _1.fnm, _4_Lucene50_0.doc, _1_Lucene50_0.tim, _2.nvd, _4_Lucene50_0.pos, _2.nvm, _2.fnm, _1_Lucene50_0.tip, _5_Lucene50_0.pos, _2.si, _5.fdt, _0_Lucene50_0.pos, _4_Lucene50_0.tip, _4_Lucene50_0.tim, _4.fdt, _3_Lucene50_0.tim, _3.fdt, _3_Lucene50_0.tip, _5_Lucene50_0.doc, _0.nvm, _0.fnm, _3.fdx, _0_Lucene50_0.doc, _1.si, _3_Lucene50_0.pos, _2.fdt, _2.fdx, _2_Lucene50_0.tim, _2_Lucene50_0.tip, _4.fdx, _1_Lucene50_0.pos, _3_Lucene50_0.doc, _1.fdx, _5_Lucene50_0.tip, _5.si, _5_Lucene50_0.tim, _1.fdt, _5.fdx, _0.si, _0_Lucene50_0.tim, _3.nvd, _4.nvm, _4.fnm, _0.fdx, _3.nvm, _3.fnm, _0_Lucene50_0.tip, _4.nvd, _2_Lucene50_0.doc, _0.fdt, _5.nvm, _5.fnm, _1_Lucene50_0.doc, _5.nvd, _4.si]
[junit4] 2> 364056 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: commit: pendingCommit != null
[junit4] 2> 364057 T759 C98 oasu.LoggingInfoStream.message [IFD][DocThread-4]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22" [6 segments ; isCommit = true]
[junit4] 2> 364057 T759 C98 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0),segFN=segments_3,generation=3}
[junit4] 2> 364057 T759 C98 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 364057 T759 C98 oasu.LoggingInfoStream.message [IFD][DocThread-4]: deleteCommits: now decRef commit "segments_2"
[junit4] 2> 364057 T759 C98 oasu.LoggingInfoStream.message [IFD][DocThread-4]: delete "segments_2"
[junit4] 2> 364057 T759 C98 oasu.LoggingInfoStream.message [IFD][DocThread-4]: 0 msec to checkpoint
[junit4] 2> 364059 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: commit: wrote segments file "segments_3"
[junit4] 2> 364059 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: commit: took 6.3 msec
[junit4] 2> 364059 T759 C98 oasu.LoggingInfoStream.message [IW][DocThread-4]: commit: done
[junit4] 2> 364059 T759 C98 oasc.SolrCore.getSearcher WARN [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 364060 T759 C98 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 364060 T759 C98 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 364061 T757 C98 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 364061 T757 C98 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 364061 T757 C98 oasc.SolrCore.getSearcher WARN [collection1] Error opening new searcher. exceeded limit of maxWarmingSearchers=2, try again later.
[junit4] 2> 364061 T757 C98 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {} 0 43
[junit4] 2> 364061 T757 C98 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: Error opening new searcher. exceeded limit of maxWarmingSearchers=2, try again later.
[junit4] 2> at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:1685)
[junit4] 2> at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:1444)
[junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:613)
[junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processCommit(RunUpdateProcessorFactory.java:95)
[junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processCommit(UpdateRequestProcessor.java:64)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalCommit(DistributedUpdateProcessor.java:1756)
[junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processCommit(DistributedUpdateProcessor.java:1733)
[junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessor.processCommit(LogUpdateProcessorFactory.java:161)
[junit4] 2> at org.apache.solr.handler.RequestHandlerUtils.handleCommit(RequestHandlerUtils.java:60)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:136)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1989)
[junit4] 2> at org.apache.solr.client.solrj.embedded.EmbeddedSolrServer.request(EmbeddedSolrServer.java:150)
[junit4] 2> at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
[junit4] 2> at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
[junit4] 2> at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
[junit4] 2> at org.apache.solr.client.solrj.LargeVolumeTestBase$DocThread.run(LargeVolumeTestBase.java:112)
[junit4] 2>
[junit4] 2> 364063 T757 oascs.LargeVolumeTestBase$DocThread.run Caught benign exception during commit: Error opening new searcher. exceeded limit of maxWarmingSearchers=2, try again later.
[junit4] 2> 364065 T756 C98 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={} {add=[T1:0 (1486486572639453184), T1:1 (1486486572640501760), T1:2 (1486486572640501761), T1:3 (1486486572640501763), T1:4 (1486486572643647488), T1:5 (1486486572643647489), T1:6 (1486486572643647490), T1:7 (1486486572643647491), T1:8 (1486486572643647492), T1:9 (1486486572643647493), ... (100 adds)]} 0 181
[junit4] 2> 364066 T756 C98 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: commit: start
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: commit: enter lock
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: commit: now prepare
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: prepareCommit: flush
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: index before flush _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DW][DocThread-1]: startFullFlush
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DW][DocThread-1]: anyChanges? numDocsInRam=12 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DWFC][DocThread-1]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 11 deleted terms (unique count=11) bytesUsed=1551, segment=_6, aborting=false, numDocsInRAM=12, deleteQueue=DWDQ: [ generation: 5 ]]
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-1]: flush postings as segment _6 numDocs=12
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-1]: new segment has 0 deleted docs
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-1]: new segment has no vectors; norms; no docValues; prox; freqs
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-1]: flushedFiles=[_6_Lucene50_0.doc, _6_Lucene50_0.tim, _6_Lucene50_0.tip, _6.fdt, _6.nvm, _6.fnm, _6.fdx, _6_Lucene50_0.pos, _6.nvd]
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-1]: flushed codec=Lucene50
[junit4] 2> 364066 T756 C98 oasu.LoggingInfoStream.message [DWPT][DocThread-1]: flushed: segment=_6 ramUsed=0.097 MB newFlushedSize(includes docstores)=0.002 MB docs/MB=6,909.891
[junit4] 2> 364069 T756 C98 oasu.LoggingInfoStream.message [DW][DocThread-1]: publishFlushedSegment seg-private updates=null
[junit4] 2> 364069 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: publishFlushedSegment
[junit4] 2> 364069 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: push deletes 12 deleted terms (unique count=12) bytesUsed=1056 delGen=15 packetCount=1 totBytesUsed=1056
[junit4] 2> 364069 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: publish sets newSegment delGen=16 seg=_6(6.0.0):C12
[junit4] 2> 364069 T756 C98 oasu.LoggingInfoStream.message [IFD][DocThread-1]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22 _6(6.0.0):C12" [7 segments ; isCommit = false]
[junit4] 2> 364069 T756 C98 oasu.LoggingInfoStream.message [IFD][DocThread-1]: 0 msec to checkpoint
[junit4] 2> 364070 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: apply all deletes during flush
[junit4] 2> 364070 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: applyDeletes: infos=[_0(6.0.0):C99, _1(6.0.0):C90, _2(6.0.0):C204, _3(6.0.0):C27, _5(6.0.0):C46, _4(6.0.0):C22, _6(6.0.0):C12] packetCount=1
[junit4] 2> 364071 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: seg=_4(6.0.0):C22 segGen=13 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364071 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: seg=_5(6.0.0):C46 segGen=13 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: seg=_3(6.0.0):C27 segGen=13 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: seg=_2(6.0.0):C204 segGen=13 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: seg=_1(6.0.0):C90 segGen=13 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: seg=_0(6.0.0):C99 segGen=13 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: applyDeletes took 4 msec
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: prune sis=segments_1: _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22 _6(6.0.0):C12 minGen=17 packetCount=1
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [BD][DocThread-1]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [DW][DocThread-1]: DocThread-1 finishFullFlush success=true
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: findMerges: 7 segments
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: seg=_2(6.0.0):C204 size=0.006 MB [floored]
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: seg=_0(6.0.0):C99 size=0.004 MB [floored]
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: seg=_1(6.0.0):C90 size=0.004 MB [floored]
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: seg=_5(6.0.0):C46 size=0.002 MB [floored]
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: seg=_4(6.0.0):C22 size=0.002 MB [floored]
[junit4] 2> 364074 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: seg=_3(6.0.0):C27 size=0.002 MB [floored]
[junit4] 2> 364077 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: seg=_6(6.0.0):C12 size=0.002 MB [floored]
[junit4] 2> 364077 T756 C98 oasu.LoggingInfoStream.message [TMP][DocThread-1]: allowedSegmentCount=1 vs count=7 (eligible count=7) tooBigCount=0
[junit4] 2> 364078 T756 C98 oasu.LoggingInfoStream.message [CMS][DocThread-1]: now merge
[junit4] 2> 364078 T756 C98 oasu.LoggingInfoStream.message [CMS][DocThread-1]: index: _0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22 _6(6.0.0):C12
[junit4] 2> 364078 T756 C98 oasu.LoggingInfoStream.message [CMS][DocThread-1]: no more merges pending; now return
[junit4] 2> 364078 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: startCommit(): start
[junit4] 2> 364078 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: startCommit index=_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22 _6(6.0.0):C12 changeCount=17
[junit4] 2> 364078 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: done all syncs: [_3.si, _1.nvd, _0.nvd, _2_Lucene50_0.pos, _1.nvm, _1.fnm, _4_Lucene50_0.doc, _6_Lucene50_0.tim, _1_Lucene50_0.tim, _2.nvd, _6_Lucene50_0.tip, _4_Lucene50_0.pos, _6.fdt, _2.nvm, _2.fnm, _1_Lucene50_0.tip, _5_Lucene50_0.pos, _2.si, _5.fdt, _0_Lucene50_0.pos, _4_Lucene50_0.tip, _6_Lucene50_0.pos, _4_Lucene50_0.tim, _6_Lucene50_0.doc, _4.fdt, _3_Lucene50_0.tim, _3.fdt, _3_Lucene50_0.tip, _5_Lucene50_0.doc, _6.si, _0.nvm, _0.fnm, _3.fdx, _0_Lucene50_0.doc, _1.si, _3_Lucene50_0.pos, _6.nvm, _6.fnm, _2.fdt, _2.fdx, _2_Lucene50_0.tim, _2_Lucene50_0.tip, _4.fdx, _6.nvd, _1_Lucene50_0.pos, _3_Lucene50_0.doc, _1.fdx, _5_Lucene50_0.tip, _5.si, _5_Lucene50_0.tim, _1.fdt, _5.fdx, _0.si, _0_Lucene50_0.tim, _3.nvd, _4.nvm, _4.fnm, _6.fdx, _0.fdx, _3.nvm, _3.fnm, _0_Lucene50_0.tip, _4.nvd, _2_Lucene50_0.doc, _0.fdt, _5.nvm, _5.fnm, _1_Lucene50_0.doc, _5.nvd, _4.si]
[junit4] 2> 364078 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: commit: pendingCommit != null
[junit4] 2> 364079 T756 C98 oasu.LoggingInfoStream.message [IFD][DocThread-1]: now checkpoint "_0(6.0.0):C99 _1(6.0.0):C90 _2(6.0.0):C204 _3(6.0.0):C27 _5(6.0.0):C46 _4(6.0.0):C22 _6(6.0.0):C12" [7 segments ; isCommit = true]
[junit4] 2> 364079 T756 C98 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62526c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a79a0),segFN=segments_4,generation=4}
[junit4] 2> 364079 T756 C98 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 364080 T756 C98 oasu.LoggingInfoStream.message [IFD][DocThread-1]: deleteCommits: now decRef commit "segments_3"
[junit4] 2> 364081 T756 C98 oasu.LoggingInfoStream.message [IFD][DocThread-1]: delete "segments_3"
[junit4] 2> 364081 T756 C98 oasu.LoggingInfoStream.message [IFD][DocThread-1]: 2 msec to checkpoint
[junit4] 2> 364081 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: commit: wrote segments file "segments_4"
[junit4] 2> 364081 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: commit: took 15.4 msec
[junit4] 2> 364081 T756 C98 oasu.LoggingInfoStream.message [IW][DocThread-1]: commit: done
[junit4] 2> 364081 T756 C98 oasc.SolrCore.getSearcher WARN [collection1] Error opening new searcher. exceeded limit of maxWarmingSearchers=2, try again later.
[junit4] 2> 364081 T756 C98 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=/update params={commit=true&softCommit=false&waitSearcher=true} {} 0 15
[junit4] 2> 364081 T756 C98 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: Error opening new searcher. exceeded limit of maxWarmingSearchers=2, try again later.
[junit4] 2> at org.apache.solr.core.SolrCore.getSearcher(SolrCore
[...truncated too long message...]
java:90)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 364174 T750 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 364174 T750 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\;done=false>>]
[junit4] 2> 364174 T750 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\
[junit4] 2> 364174 T750 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\index;done=false>>]
[junit4] 2> 364174 T750 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\index
[junit4] 2> 364175 T750 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 364175 T750 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=29115370
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {}, docValues:{}, sim=DefaultSimilarity, locale=de_AT, timezone=Asia/Oral
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_40-ea (32-bit)/cpus=2,threads=1,free=52737384,total=234618880
[junit4] 2> NOTE: All tests run in this JVM: [ExternalHttpClientTest, SolrExampleEmbeddedTest, SolrSchemalessExampleTest, TestXMLEscaping, TestSolrProperties, LargeVolumeBinaryJettyTest, DocumentAnalysisResponseTest, CloudSolrServerMultiConstructorTest, JettyWebappTest, TestJsonRecordReader, CloudSolrServerTest, ModifiableSolrParamsTest, IteratorChainTest, TestFastInputStream, SolrParamTest, SolrExampleStreamingTest, FieldAnalysisResponseTest, MultiCoreEmbeddedTest, TestBatchUpdate, TestEmbeddedSolrServer, TermsResponseTest, SolrPingTest, TestNamedListCodec, TestLBHttpSolrServer, TestCoreAdmin, SolrExceptionTest, SolrExampleStreamingBinaryTest, SolrExampleBinaryTest, SolrExampleXMLTest, SolrExampleJettyTest, BasicHttpSolrServerTest, MultiCoreExampleJettyTest, LargeVolumeEmbeddedTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=LargeVolumeEmbeddedTest -Dtests.seed=54CFA8328E33D17 -Dtests.slow=true -Dtests.locale=de_AT -Dtests.timezone=Asia/Oral -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s | LargeVolumeEmbeddedTest (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog\tlog.0000000000000000003: java.nio.file.FileSystemException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog\tlog.0000000000000000003: The process cannot access the file because it is being used by another process.
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001\tlog
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001\init-core-data-001
[junit4] > C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001: java.nio.file.DirectoryNotEmptyException: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.embedded.LargeVolumeEmbeddedTest-54CFA8328E33D17-001
[junit4] > at __randomizedtesting.SeedInfo.seed([54CFA8328E33D17]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:281)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed in 2.03s, 1 test, 1 error <<< FAILURES!
[...truncated 69 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:200: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1349: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:956: There were test failures: 54 suites, 332 tests, 1 suite-level error
Total time: 158 minutes 20 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_40-ea-b09 -server -XX:+UseParallelGC (asserts: true)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any