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
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
