Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/11113/
Java: 64bit/jdk1.9.0-ea-b28 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION:
org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest.testChildDoctransformer
Error Message:
Expected mime type application/octet-stream but got text/html. <html> <head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 500 Server Error</title> </head> <body> <h2>HTTP ERROR: 500</h2>
<p>Problem accessing /solr/collection1/select. Reason: <pre> Server
Error</pre></p> <hr /><i><small>Powered by Jetty://</small></i>
</body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Expected
mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 500 Server Error</title>
</head>
<body>
<h2>HTTP ERROR: 500</h2>
<p>Problem accessing /solr/collection1/select. Reason:
<pre> Server Error</pre></p>
<hr /><i><small>Powered by Jetty://</small></i>
</body>
</html>
at
__randomizedtesting.SeedInfo.seed([4E1263FB5C7A9655:3DC87C61D062E153]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:512)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:281)
at
org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at
org.apache.solr.client.solrj.SolrExampleTests.testChildDoctransformer(SolrExampleTests.java:1373)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:484)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
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 12536 lines...]
[junit4] Suite:
org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/init-core-data-001
[junit4] 2> 30744 T122 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 30744 T122 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 30745 T122 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 30774 T122 oejs.AbstractConnector.doStart Started
[email protected]:56794
[junit4] 2> 30774 T122 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 30775 T122 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 30775 T122 oasc.SolrResourceLoader.locateSolrHome using system
property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4] 2> 30775 T122 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4] 2> 30784 T122 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/solr.xml
[junit4] 2> 30796 T122 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4] 2> 30797 T122 oasc.CoreContainer.<init> New CoreContainer
1961343286
[junit4] 2> 30797 T122 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/]
[junit4] 2> 30797 T122 oashc.HttpShardHandlerFactory.getParameter Setting
socketTimeout to: 0
[junit4] 2> 30798 T122 oashc.HttpShardHandlerFactory.getParameter Setting
urlScheme to: null
[junit4] 2> 30798 T122 oashc.HttpShardHandlerFactory.getParameter Setting
connTimeout to: 0
[junit4] 2> 30798 T122 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnectionsPerHost to: 20
[junit4] 2> 30798 T122 oashc.HttpShardHandlerFactory.getParameter Setting
corePoolSize to: 0
[junit4] 2> 30799 T122 oashc.HttpShardHandlerFactory.getParameter Setting
maximumPoolSize to: 2147483647
[junit4] 2> 30799 T122 oashc.HttpShardHandlerFactory.getParameter Setting
maxThreadIdleTime to: 5
[junit4] 2> 30799 T122 oashc.HttpShardHandlerFactory.getParameter Setting
sizeOfQueue to: -1
[junit4] 2> 30799 T122 oashc.HttpShardHandlerFactory.getParameter Setting
fairnessPolicy to: false
[junit4] 2> 30800 T122 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 30800 T122 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 30801 T122 oasl.LogWatcher.newRegisteredLogWatcher Registering
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 30801 T122 oasc.CoreContainer.load Host Name:
[junit4] 2> 30803 T122 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4] 2> 30806 T122 oasc.CorePropertiesLocator.discoverUnder Found core
collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/
[junit4] 2> 30808 T122 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 30809 T132 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/'
[junit4] 2> 30832 T132 oasc.SolrConfig.initLibs Adding specified lib dirs
to ClassLoader
[junit4] 2> 30833 T132 oasc.SolrResourceLoader.addToClassLoader WARN No
files added to classloader from lib: ../../../contrib/extraction/lib (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 30834 T132 oasc.SolrResourceLoader.addToClassLoader WARN Can't
find (or read) directory to add to classloader: ../../../dist/ (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 30834 T132 oasc.SolrResourceLoader.addToClassLoader WARN No
files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 30834 T132 oasc.SolrResourceLoader.addToClassLoader WARN Can't
find (or read) directory to add to classloader: ../../../dist/ (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 30835 T132 oasc.SolrResourceLoader.addToClassLoader WARN No
files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 30835 T132 oasc.SolrResourceLoader.addToClassLoader WARN Can't
find (or read) directory to add to classloader: ../../../dist/ (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 30835 T132 oasc.SolrResourceLoader.addToClassLoader WARN No
files added to classloader from lib: ../../../contrib/velocity/lib (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 30835 T132 oasc.SolrResourceLoader.addToClassLoader WARN Can't
find (or read) directory to add to classloader: ../../../dist/ (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 30856 T132 oasu.SolrIndexConfig.<init> IndexWriter infoStream
solr logging is enabled
[junit4] 2> 30858 T132 oasc.SolrConfig.<init> Using Lucene MatchVersion:
4.11.0
[junit4] 2> 30899 T132 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 30900 T132 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 30913 T132 oass.IndexSchema.readSchema [collection1] Schema
name=example
[junit4] 2> 31135 T132 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 31136 T132 oasr.RestManager$Registry.registerManagedResource
Registered ManagedResource impl
org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path
/schema/analysis/stopwords/english
[junit4] 2> 31137 T132 oasr.RestManager$Registry.registerManagedResource
Registered ManagedResource impl
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager
for path /schema/analysis/synonyms/english
[junit4] 2> 31150 T132 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 31152 T132 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 31170 T132 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from instancedir
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/
[junit4] 2> 31170 T132 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 31170 T132 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/
[junit4] 2> 31170 T132 oasc.JmxMonitoredMap.<init> No JMX servers found,
not exposing Solr information with JMX.
[junit4] 2> 31170 T132 oasc.SolrCore.initListeners [collection1] Added
SolrEventListener for newSearcher:
org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 31171 T132 oasc.SolrCore.initListeners [collection1] Added
SolrEventListener for firstSearcher:
org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher
warming in solrconfig.xml}]}
[junit4] 2> 31171 T132 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001
[junit4] 2> 31171 T132 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index/
[junit4] 2> 31171 T132 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index'
doesn't exist. Creating new index...
[junit4] 2> 31171 T132 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index
[junit4] 2> 31172 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: init: current segments file is "null";
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@14c92328
[junit4] 2> 31172 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 31172 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: 0 msec to checkpoint
[junit4] 2> 31173 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: init: create=true
[junit4] 2> 31173 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index)
[junit4] 2> index=
[junit4] 2> version=4.11.0
[junit4] 2> matchVersion=4.11.0
[junit4] 2> analyzer=null
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> maxBufferedDeleteTerms=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2> readerTermsIndexDivisor=1
[junit4] 2> termIndexInterval=32
[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=Lucene410
[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@446424d4
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> checkIntegrityAtMerge=false
[junit4] 2> writer=org.apache.lucene.util.SetOnce@4c83ce45
[junit4] 2>
[junit4] 2> 31173 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: now flush at close
[junit4] 2> 31173 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 31173 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: index before flush
[junit4] 2> 31173 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: startFullFlush
[junit4] 2> 31174 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31174 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: coreLoadExecutor-124-thread-1
finishFullFlush success=true
[junit4] 2> 31174 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: apply all deletes during flush
[junit4] 2> 31174 T132 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-124-thread-1]: prune sis=null:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 31174 T132 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-124-thread-1]: now merge
[junit4] 2> 31174 T132 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-124-thread-1]: index:
[junit4] 2> 31175 T132 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-124-thread-1]: no more merges pending; now return
[junit4] 2> 31175 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: waitForMerges
[junit4] 2> 31175 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: waitForMerges done
[junit4] 2> 31175 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: commit: start
[junit4] 2> 31175 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: commit: enter lock
[junit4] 2> 31175 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: commit: now prepare
[junit4] 2> 31175 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: prepareCommit: flush
[junit4] 2> 31175 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: index before flush
[junit4] 2> 31176 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: startFullFlush
[junit4] 2> 31176 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31176 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: apply all deletes during flush
[junit4] 2> 31176 T132 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-124-thread-1]: prune sis=null:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 31176 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: coreLoadExecutor-124-thread-1
finishFullFlush success=true
[junit4] 2> 31176 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: startCommit(): start
[junit4] 2> 31176 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: startCommit index= changeCount=1
[junit4] 2> 31177 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: done all syncs: []
[junit4] 2> 31177 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: commit: pendingCommit != null
[junit4] 2> 31177 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: commit: wrote segments file "segments_1"
[junit4] 2> 31177 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: now checkpoint "" [0 segments ; isCommit
= true]
[junit4] 2> 31177 T132 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_1,generation=1}
[junit4] 2> 31177 T132 oasc.SolrDeletionPolicy.updateCommits newest commit
generation = 1
[junit4] 2> 31178 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: 0 msec to checkpoint
[junit4] 2> 31178 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: commit: took 2.4 msec
[junit4] 2> 31178 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: commit: done
[junit4] 2> 31178 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: rollback
[junit4] 2> 31178 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: all running merges have aborted
[junit4] 2> 31178 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: rollback: done finish merges
[junit4] 2> 31178 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: abort
[junit4] 2> 31179 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: done abort; abortedFiles=[] success=true
[junit4] 2> 31179 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: rollback: infos=
[junit4] 2> 31179 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 31179 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: 0 msec to checkpoint
[junit4] 2> 31179 T132 oasc.SolrCore.initWriters created json:
solr.JSONResponseWriter
[junit4] 2> 31179 T132 oasc.SolrCore.initWriters adding lazy
queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 31179 T132 oasc.SolrCore.initWriters created velocity:
solr.VelocityResponseWriter
[junit4] 2> 31180 T132 oasc.SolrCore.initWriters created xslt:
solr.XSLTResponseWriter
[junit4] 2> 31180 T132 oasr.XSLTResponseWriter.init
xsltCacheLifetimeSeconds=5
[junit4] 2> 31180 T132 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 31181 T132 oasc.RequestHandlers.initHandlersFromConfig created
/update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 31181 T132 oasc.RequestHandlers.initHandlersFromConfig created
/update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 31181 T132 oasc.RequestHandlers.initHandlersFromConfig created
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 31181 T132 oasc.RequestHandlers.initHandlersFromConfig created
/update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 31181 T132 oasc.RequestHandlers.initHandlersFromConfig created
/select: solr.SearchHandler
[junit4] 2> 31181 T132 oasc.RequestHandlers.initHandlersFromConfig created
/query: solr.SearchHandler
[junit4] 2> 31182 T132 oasc.RequestHandlers.initHandlersFromConfig created
/get: solr.RealTimeGetHandler
[junit4] 2> 31182 T132 oasc.RequestHandlers.initHandlersFromConfig created
/export: solr.SearchHandler
[junit4] 2> 31182 T132 oasc.RequestHandlers.initHandlersFromConfig created
/browse: solr.SearchHandler
[junit4] 2> 31182 T132 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 2> 31182 T132 oasc.RequestHandlers.initHandlersFromConfig created
/update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 2> 31183 T132 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 31183 T132 oasc.RequestHandlers.initHandlersFromConfig created
/analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 31183 T132 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 31183 T132 oasc.RequestHandlers.initHandlersFromConfig created
/analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 31183 T132 oasc.RequestHandlers.initHandlersFromConfig created
/admin/: solr.admin.AdminHandlers
[junit4] 2> 31183 T132 oasc.RequestHandlers.initHandlersFromConfig created
/admin/ping: solr.PingRequestHandler
[junit4] 2> 31183 T132 oasc.RequestHandlers.initHandlersFromConfig created
/debug/dump: solr.DumpRequestHandler
[junit4] 2> 31184 T132 oasc.RequestHandlers.initHandlersFromConfig created
/replication: solr.ReplicationHandler
[junit4] 2> 31184 T132 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 31184 T132 oasc.RequestHandlers.initHandlersFromConfig created
/spell: solr.SearchHandler
[junit4] 2> 31184 T132 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 31184 T132 oasc.RequestHandlers.initHandlersFromConfig created
/suggest: solr.SearchHandler
[junit4] 2> 31184 T132 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 31184 T132 oasc.RequestHandlers.initHandlersFromConfig created
/tvrh: solr.SearchHandler
[junit4] 2> 31184 T132 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 31185 T132 oasc.RequestHandlers.initHandlersFromConfig created
/terms: solr.SearchHandler
[junit4] 2> 31185 T132 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 31185 T132 oasc.RequestHandlers.initHandlersFromConfig created
/elevate: solr.SearchHandler
[junit4] 2> 31186 T132 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 31187 T132 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 31188 T132 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 31188 T132 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 31191 T132 oasu.CommitTracker.<init> Hard AutoCommit: if
uncommited for 15000ms;
[junit4] 2> 31191 T132 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 31192 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: init: current segments file is
"segments_1";
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@14c92328
[junit4] 2> 31192 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: init: load commit "segments_1"
[junit4] 2> 31192 T132 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_1,generation=1}
[junit4] 2> 31193 T132 oasc.SolrDeletionPolicy.updateCommits newest commit
generation = 1
[junit4] 2> 31193 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 31193 T132 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-124-thread-1]: 0 msec to checkpoint
[junit4] 2> 31193 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: init: create=false
[junit4] 2> 31193 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index)
[junit4] 2> index=
[junit4] 2> version=4.11.0
[junit4] 2> matchVersion=4.11.0
[junit4] 2> analyzer=null
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> maxBufferedDeleteTerms=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2> readerTermsIndexDivisor=1
[junit4] 2> termIndexInterval=32
[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=Lucene410
[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@53cd1b16
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> checkIntegrityAtMerge=false
[junit4] 2> writer=org.apache.lucene.util.SetOnce@5f83e99
[junit4] 2>
[junit4] 2> 31194 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: flush at getReader
[junit4] 2> 31194 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: startFullFlush
[junit4] 2> 31194 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31194 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: apply all deletes during flush
[junit4] 2> 31194 T132 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-124-thread-1]: prune sis=segments_1:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 31195 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: return reader version=1
reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 31195 T132 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-124-thread-1]: coreLoadExecutor-124-thread-1
finishFullFlush success=true
[junit4] 2> 31195 T132 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-124-thread-1]: getReader took 1 msec
[junit4] 2> 31195 T132 oass.SolrIndexSearcher.<init> Opening
Searcher@59ef4f32[collection1] main
[junit4] 2> 31195 T132 oasr.ManagedResourceStorage.newStorageIO WARN
Cannot write to config directory
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 31196 T132 oasr.RestManager.init Initializing RestManager with
initArgs: {}
[junit4] 2> 31196 T132 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using InMemoryStorage
[junit4] 2> 31196 T132 oasr.ManagedResource.reloadFromStorage WARN No
stored data found for /rest/managed
[junit4] 2> 31196 T132 oasr.ManagedResourceStorage$JsonStorage.store Saved
JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 31196 T132 oasr.RestManager.init Initializing 2 registered
ManagedResources
[junit4] 2> 31196 T132 oasr.ManagedResourceStorage.load Reading
_schema_analysis_stopwords_english.json using InMemoryStorage
[junit4] 2> 31197 T132 oasr.ManagedResource.reloadFromStorage WARN No
stored data found for /schema/analysis/stopwords/english
[junit4] 2> 31197 T132 oasr.ManagedResourceStorage$JsonStorage.store Saved
JSON object to path _schema_analysis_stopwords_english.json using
InMemoryStorage
[junit4] 2> 31197 T132
oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for
/schema/analysis/stopwords/english
[junit4] 2> 31197 T132 oasr.ManagedResource.notifyObserversDuringInit
Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 31197 T132 oasr.ManagedResourceStorage.load Reading
_schema_analysis_synonyms_english.json using InMemoryStorage
[junit4] 2> 31197 T132 oasr.ManagedResource.reloadFromStorage WARN No
stored data found for /schema/analysis/synonyms/english
[junit4] 2> 31198 T132
oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage
Loaded 0 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 31198 T132 oasr.ManagedResource.notifyObserversDuringInit
Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 31198 T132 oashc.SpellCheckComponent.inform Initializing spell
checkers
[junit4] 2> 31198 T132 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> 31198 T132 oashc.SpellCheckComponent.inform No queryConverter
defined, using default converter
[junit4] 2> 31199 T132 oashc.SuggestComponent.inform Initializing
SuggestComponent
[junit4] 2> 31199 T132 oasss.SolrSuggester.init init:
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
[junit4] 2> 31199 T132 oasss.SolrSuggester.init Dictionary loaded with
params:
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
[junit4] 2> 31199 T132 oashc.QueryElevationComponent.inform Loading
QueryElevation from:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4] 2> 31204 T132 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 31204 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@59ef4f32[collection1]
main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 31204 T132 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 31205 T122 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0
[junit4] 2> 31205 T122 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 31205 T122 oas.SolrJettyTestBase.createJetty Jetty Assigned
Port#56794
[junit4] 2> ASYNC NEW_CORE C11 name=collection1
org.apache.solr.core.SolrCore@33e59618
[junit4] 2> 31206 T133 C11 oasc.SolrCore.execute [collection1] webapp=null
path=null
params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher}
hits=0 status=0 QTime=1
[junit4] 2> 31206 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 31206 T133
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index
for spellchecker: default
[junit4] 2> 31207 T133
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index
for spellchecker: wordbreak
[junit4] 2> 31207 T133
oashc.SuggestComponent$SuggesterListener.newSearcher Loading suggester index
for: mySuggester
[junit4] 2> 31207 T133 oasss.SolrSuggester.reload reload()
[junit4] 2> 31207 T133 oasss.SolrSuggester.build build()
[junit4] 2> 31208 T133 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@59ef4f32[collection1]
main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 31211 T122 oas.SolrTestCaseJ4.setUp ###Starting testWaitOptions
[junit4] 2> 31212 T122 oascsi.ConcurrentUpdateSolrServer.request blocking
for commit/optimize
[junit4] 2> 31216 T130 C11 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 31216 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: commit: start
[junit4] 2> 31216 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: commit: enter lock
[junit4] 2> 31217 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: commit: now prepare
[junit4] 2> 31217 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: prepareCommit: flush
[junit4] 2> 31217 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: index before flush
[junit4] 2> 31217 T130 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-130]: startFullFlush
[junit4] 2> 31219 T130 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-130]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 31219 T130 C11 oasu.LoggingInfoStream.message
[DWFC][qtp46220050-130]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=_0, aborting=false, numDocsInRAM=1,
deleteQueue=DWDQ: [ generation: 1 ]]
[junit4] 2> 31219 T130 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-130]: flush postings as segment _0 numDocs=1
[junit4] 2> 31220 T130 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-130]: new segment has 0 deleted docs
[junit4] 2> 31221 T130 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-130]: new segment has no vectors; no norms; no docValues; no
prox; no freqs
[junit4] 2> 31221 T130 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-130]: flushedFiles=[_0_Lucene41_0.tip, _0_Lucene41_0.doc,
_0_Lucene41_0.tim, _0.fdx, _0.fdt, _0.fnm]
[junit4] 2> 31221 T130 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-130]: flushed codec=Lucene410
[junit4] 2> 31221 T130 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-130]: flushed: segment=_0 ramUsed=0.063 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=1,456.356
[junit4] 2> 31221 T130 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-130]: publishFlushedSegment seg-private updates=null
[junit4] 2> 31222 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: publishFlushedSegment
[junit4] 2> 31222 T130 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-130]: push deletes 1 deleted terms (unique count=1)
bytesUsed=1056 delGen=2 packetCount=1 totBytesUsed=1056
[junit4] 2> 31222 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: publish sets newSegment delGen=3 seg=_0(4.11.0):C1
[junit4] 2> 31223 T130 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-130]: now checkpoint "_0(4.11.0):C1" [1 segments ; isCommit =
false]
[junit4] 2> 31223 T130 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-130]: 0 msec to checkpoint
[junit4] 2> 31223 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: apply all deletes during flush
[junit4] 2> 31223 T130 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-130]: applyDeletes: infos=[_0(4.11.0):C1] packetCount=1
[junit4] 2> 31223 T130 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-130]: applyDeletes took 0 msec
[junit4] 2> 31224 T130 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-130]: prune sis=segments_1: _0(4.11.0):C1 minGen=4
packetCount=1
[junit4] 2> 31224 T130 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-130]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 31224 T130 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-130]: qtp46220050-130 finishFullFlush success=true
[junit4] 2> 31224 T130 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-130]: findMerges: 1 segments
[junit4] 2> 31225 T130 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-130]: seg=_0(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31225 T130 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-130]: allowedSegmentCount=1 vs count=1 (eligible count=1)
tooBigCount=0
[junit4] 2> 31225 T130 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-130]: now merge
[junit4] 2> 31225 T130 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-130]: index: _0(4.11.0):C1
[junit4] 2> 31226 T130 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-130]: no more merges pending; now return
[junit4] 2> 31226 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: startCommit(): start
[junit4] 2> 31226 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: startCommit index=_0(4.11.0):C1 changeCount=3
[junit4] 2> 31227 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: done all syncs: [_0_Lucene41_0.tip, _0_Lucene41_0.doc,
_0.si, _0_Lucene41_0.tim, _0.fdx, _0.fdt, _0.fnm]
[junit4] 2> 31227 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: commit: pendingCommit != null
[junit4] 2> 31227 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: commit: wrote segments file "segments_2"
[junit4] 2> 31227 T130 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-130]: now checkpoint "_0(4.11.0):C1" [1 segments ; isCommit =
true]
[junit4] 2> 31227 T130 C11 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_1,generation=1}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_2,generation=2}
[junit4] 2> 31228 T130 C11 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 2
[junit4] 2> 31228 T130 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-130]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 31228 T130 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-130]: delete "segments_1"
[junit4] 2> 31229 T130 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-130]: 1 msec to checkpoint
[junit4] 2> 31229 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: commit: took 11.8 msec
[junit4] 2> 31229 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: commit: done
[junit4] 2> 31230 T130 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-130]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31230 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 31230 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: flush at getReader
[junit4] 2> 31230 T130 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-130]: startFullFlush
[junit4] 2> 31230 T130 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-130]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31231 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: apply all deletes during flush
[junit4] 2> 31231 T130 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-130]: applyDeletes: no deletes; skipping
[junit4] 2> 31231 T130 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-130]: prune sis=segments_2: _0(4.11.0):C1 minGen=4
packetCount=0
[junit4] 2> 31232 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: return reader version=3
reader=StandardDirectoryReader(segments_2:3:nrt _0(4.11.0):C1)
[junit4] 2> 31232 T130 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-130]: qtp46220050-130 finishFullFlush success=true
[junit4] 2> 31232 T130 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-130]: getReader took 2 msec
[junit4] 2> 31232 T130 C11 oass.SolrIndexSearcher.<init> Opening
Searcher@48a2a2e7[collection1] main
[junit4] 2> 31233 T130 C11 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 31233 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@48a2a2e7[collection1]
main{StandardDirectoryReader(segments_2:3:nrt _0(4.11.0):C1)}
[junit4] 2> 31233 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 31233 T133 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@48a2a2e7[collection1]
main{StandardDirectoryReader(segments_2:3:nrt _0(4.11.0):C1)}
[junit4] 2> 31234 T130 C11 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{add=[42 (1479244848160571392)],commit=} 0 20
[junit4] 2> 31235 T122 oascsi.ConcurrentUpdateSolrServer.request blocking
for commit/optimize
[junit4] 2> 31241 T131 C11 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 31241 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: commit: start
[junit4] 2> 31242 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: commit: enter lock
[junit4] 2> 31242 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: commit: now prepare
[junit4] 2> 31242 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: prepareCommit: flush
[junit4] 2> 31243 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: index before flush _0(4.11.0):C1
[junit4] 2> 31243 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: startFullFlush
[junit4] 2> 31243 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 31244 T131 C11 oasu.LoggingInfoStream.message
[DWFC][qtp46220050-131]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=_1, aborting=false, numDocsInRAM=1,
deleteQueue=DWDQ: [ generation: 3 ]]
[junit4] 2> 31244 T131 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-131]: flush postings as segment _1 numDocs=1
[junit4] 2> 31245 T131 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-131]: new segment has 0 deleted docs
[junit4] 2> 31245 T131 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-131]: new segment has no vectors; no norms; no docValues; no
prox; no freqs
[junit4] 2> 31245 T131 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-131]: flushedFiles=[_1_Lucene41_0.tip, _1.fdx,
_1_Lucene41_0.doc, _1_Lucene41_0.tim, _1.fnm, _1.fdt]
[junit4] 2> 31245 T131 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-131]: flushed codec=Lucene410
[junit4] 2> 31245 T131 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-131]: flushed: segment=_1 ramUsed=0.063 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=1,456.356
[junit4] 2> 31246 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: publishFlushedSegment seg-private updates=null
[junit4] 2> 31246 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: publishFlushedSegment
[junit4] 2> 31246 T131 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-131]: push deletes 1 deleted terms (unique count=1)
bytesUsed=1056 delGen=6 packetCount=1 totBytesUsed=1056
[junit4] 2> 31246 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: publish sets newSegment delGen=7 seg=_1(4.11.0):C1
[junit4] 2> 31247 T131 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-131]: now checkpoint "_0(4.11.0):C1 _1(4.11.0):C1" [2
segments ; isCommit = false]
[junit4] 2> 31247 T131 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-131]: 0 msec to checkpoint
[junit4] 2> 31247 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: apply all deletes during flush
[junit4] 2> 31247 T131 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-131]: applyDeletes: infos=[_0(4.11.0):C1, _1(4.11.0):C1]
packetCount=1
[junit4] 2> 31248 T131 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-131]: seg=_0(4.11.0):C1 segGen=4 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31248 T131 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-131]: applyDeletes took 1 msec
[junit4] 2> 31248 T131 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-131]: prune sis=segments_2: _0(4.11.0):C1 _1(4.11.0):C1
minGen=8 packetCount=1
[junit4] 2> 31248 T131 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-131]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 31249 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: qtp46220050-131 finishFullFlush success=true
[junit4] 2> 31249 T131 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-131]: findMerges: 2 segments
[junit4] 2> 31249 T131 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-131]: seg=_0(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31250 T131 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-131]: seg=_1(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31250 T131 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-131]: allowedSegmentCount=1 vs count=2 (eligible count=2)
tooBigCount=0
[junit4] 2> 31250 T131 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-131]: now merge
[junit4] 2> 31251 T131 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-131]: index: _0(4.11.0):C1 _1(4.11.0):C1
[junit4] 2> 31251 T131 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-131]: no more merges pending; now return
[junit4] 2> 31251 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: startCommit(): start
[junit4] 2> 31251 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: startCommit index=_0(4.11.0):C1 _1(4.11.0):C1
changeCount=6
[junit4] 2> 31252 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: done all syncs: [_0_Lucene41_0.tip, _0_Lucene41_0.doc,
_0.si, _0_Lucene41_0.tim, _1.si, _0.fdx, _1.fnm, _0.fdt, _1_Lucene41_0.tip,
_1.fdx, _1_Lucene41_0.doc, _1_Lucene41_0.tim, _0.fnm, _1.fdt]
[junit4] 2> 31252 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: commit: pendingCommit != null
[junit4] 2> 31253 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: commit: wrote segments file "segments_3"
[junit4] 2> 31253 T131 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-131]: now checkpoint "_0(4.11.0):C1 _1(4.11.0):C1" [2
segments ; isCommit = true]
[junit4] 2> 31253 T131 C11 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_3,generation=3}
[junit4] 2> 31253 T131 C11 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 3
[junit4] 2> 31254 T131 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-131]: deleteCommits: now decRef commit "segments_2"
[junit4] 2> 31254 T131 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-131]: delete "segments_2"
[junit4] 2> 31254 T131 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-131]: 1 msec to checkpoint
[junit4] 2> 31254 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: commit: took 11.8 msec
[junit4] 2> 31255 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: commit: done
[junit4] 2> 31255 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31255 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 31255 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: flush at getReader
[junit4] 2> 31255 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: startFullFlush
[junit4] 2> 31255 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31256 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: apply all deletes during flush
[junit4] 2> 31256 T131 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-131]: applyDeletes: no deletes; skipping
[junit4] 2> 31256 T131 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-131]: prune sis=segments_3: _0(4.11.0):C1 _1(4.11.0):C1
minGen=8 packetCount=0
[junit4] 2> 31257 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: return reader version=5
reader=StandardDirectoryReader(segments_3:5:nrt _0(4.11.0):C1 _1(4.11.0):C1)
[junit4] 2> 31257 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: qtp46220050-131 finishFullFlush success=true
[junit4] 2> 31258 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: getReader took 3 msec
[junit4] 2> 31258 T131 C11 oass.SolrIndexSearcher.<init> Opening
Searcher@a6ac5b[collection1] main
[junit4] 2> 31258 T131 C11 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 31258 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@a6ac5b[collection1]
main{StandardDirectoryReader(segments_3:5:nrt _0(4.11.0):C1 _1(4.11.0):C1)}
[junit4] 2> 31259 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 31259 T133 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@a6ac5b[collection1]
main{StandardDirectoryReader(segments_3:5:nrt _0(4.11.0):C1 _1(4.11.0):C1)}
[junit4] 2> 31489 T131 C11 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 31490 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31490 T131 C11 oasu.DirectUpdateHandler2.commit No uncommitted
changes. Skipping IW.commit.
[junit4] 2> 31490 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31490 T131 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-131]: nrtIsCurrent: infoVersion matches: true; DW changes:
false; BD changes: false
[junit4] 2> 31490 T131 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-131]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31491 T131 C11 oasc.SolrCore.openNewSearcher SolrIndexSearcher
has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 31491 T131 C11 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 31491 T131 C11 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=false&commit=true&softCommit=false&wt=javabin&version=2}
{add=[43 (1479244848186785792)],commit=,commit=} 0 252
[junit4] 2> 31497 T127 C11 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 31497 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: commit: start
[junit4] 2> 31497 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: commit: enter lock
[junit4] 2> 31498 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: commit: now prepare
[junit4] 2> 31498 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: prepareCommit: flush
[junit4] 2> 31498 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: index before flush _0(4.11.0):C1 _1(4.11.0):C1
[junit4] 2> 31498 T127 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: startFullFlush
[junit4] 2> 31499 T127 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 31499 T127 C11 oasu.LoggingInfoStream.message
[DWFC][qtp46220050-127]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=_2, aborting=false, numDocsInRAM=1,
deleteQueue=DWDQ: [ generation: 5 ]]
[junit4] 2> 31499 T127 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: flush postings as segment _2 numDocs=1
[junit4] 2> 31500 T127 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: new segment has 0 deleted docs
[junit4] 2> 31500 T127 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: new segment has no vectors; no norms; no docValues; no
prox; no freqs
[junit4] 2> 31500 T127 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: flushedFiles=[_2_Lucene41_0.tip, _2.fdt,
_2_Lucene41_0.doc, _2_Lucene41_0.tim, _2.fnm, _2.fdx]
[junit4] 2> 31500 T127 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: flushed codec=Lucene410
[junit4] 2> 31501 T127 C11 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: flushed: segment=_2 ramUsed=0.063 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=1,456.356
[junit4] 2> 31501 T127 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: publishFlushedSegment seg-private updates=null
[junit4] 2> 31501 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: publishFlushedSegment
[junit4] 2> 31501 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: push deletes 1 deleted terms (unique count=1)
bytesUsed=1056 delGen=10 packetCount=1 totBytesUsed=1056
[junit4] 2> 31501 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: publish sets newSegment delGen=11 seg=_2(4.11.0):C1
[junit4] 2> 31502 T127 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-127]: now checkpoint "_0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1" [3 segments ; isCommit = false]
[junit4] 2> 31502 T127 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-127]: 0 msec to checkpoint
[junit4] 2> 31502 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: apply all deletes during flush
[junit4] 2> 31502 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: applyDeletes: infos=[_0(4.11.0):C1, _1(4.11.0):C1,
_2(4.11.0):C1] packetCount=1
[junit4] 2> 31503 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: seg=_1(4.11.0):C1 segGen=8 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31503 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: seg=_0(4.11.0):C1 segGen=8 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31503 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: applyDeletes took 1 msec
[junit4] 2> 31503 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: prune sis=segments_3: _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 minGen=12 packetCount=1
[junit4] 2> 31504 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 31504 T127 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: qtp46220050-127 finishFullFlush success=true
[junit4] 2> 31504 T127 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: findMerges: 3 segments
[junit4] 2> 31504 T127 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: seg=_0(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31505 T127 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: seg=_1(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31505 T127 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: seg=_2(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31505 T127 C11 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: allowedSegmentCount=1 vs count=3 (eligible count=3)
tooBigCount=0
[junit4] 2> 31505 T127 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-127]: now merge
[junit4] 2> 31505 T127 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-127]: index: _0(4.11.0):C1 _1(4.11.0):C1 _2(4.11.0):C1
[junit4] 2> 31506 T127 C11 oasu.LoggingInfoStream.message
[CMS][qtp46220050-127]: no more merges pending; now return
[junit4] 2> 31506 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: startCommit(): start
[junit4] 2> 31506 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: startCommit index=_0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 changeCount=9
[junit4] 2> 31507 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: done all syncs: [_0_Lucene41_0.tip, _0_Lucene41_0.doc,
_0.si, _0_Lucene41_0.tim, _1.si, _2.si, _2_Lucene41_0.tip, _2.fdt,
_2_Lucene41_0.doc, _2_Lucene41_0.tim, _0.fdx, _1.fnm, _2.fdx, _0.fdt,
_1_Lucene41_0.tip, _2.fnm, _1.fdx, _1_Lucene41_0.doc, _1_Lucene41_0.tim,
_0.fnm, _1.fdt]
[junit4] 2> 31507 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: commit: pendingCommit != null
[junit4] 2> 31507 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: commit: wrote segments file "segments_4"
[junit4] 2> 31507 T127 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-127]: now checkpoint "_0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1" [3 segments ; isCommit = true]
[junit4] 2> 31508 T127 C11 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_3,generation=3}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_4,generation=4}
[junit4] 2> 31508 T127 C11 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 4
[junit4] 2> 31508 T127 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-127]: deleteCommits: now decRef commit "segments_3"
[junit4] 2> 31508 T127 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-127]: delete "segments_3"
[junit4] 2> 31508 T127 C11 oasu.LoggingInfoStream.message
[IFD][qtp46220050-127]: 1 msec to checkpoint
[junit4] 2> 31509 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: commit: took 11.0 msec
[junit4] 2> 31509 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: commit: done
[junit4] 2> 31509 T127 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31509 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 31510 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: flush at getReader
[junit4] 2> 31510 T127 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: startFullFlush
[junit4] 2> 31510 T127 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31510 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: apply all deletes during flush
[junit4] 2> 31510 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: applyDeletes: no deletes; skipping
[junit4] 2> 31511 T127 C11 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: prune sis=segments_4: _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 minGen=12 packetCount=0
[junit4] 2> 31512 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: return reader version=7
reader=StandardDirectoryReader(segments_4:7:nrt _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1)
[junit4] 2> 31512 T127 C11 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: qtp46220050-127 finishFullFlush success=true
[junit4] 2> 31512 T127 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: getReader took 2 msec
[junit4] 2> 31512 T127 C11 oass.SolrIndexSearcher.<init> Opening
Searcher@5fbe4573[collection1] main
[junit4] 2> 31513 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@5fbe4573[collection1]
main{StandardDirectoryReader(segments_4:7:nrt _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1)}
[junit4] 2> 31513 T127 C11 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 31513 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 31513 T133 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@5fbe4573[collection1]
main{StandardDirectoryReader(segments_4:7:nrt _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1)}
[junit4] 2> 31513 T127 C11 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{add=[44 (1479244848455221248)],commit=} 0 18
[junit4] 2> 31514 T122 oascsi.ConcurrentUpdateSolrServer.request blocking
for commit/optimize
[junit4] 2> 31518 T129 C11 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 31519 T129 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: commit: start
[junit4] 2> 31519 T129 C11 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: commit: enter lock
[junit4] 2> ASYNC NEW_CORE C12 name=collection1
org.apache.solr.core.SolrCore@33e59618
[junit4] 2> 31523 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: commit: now prepare
[junit4] 2> 31523 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: prepareCommit: flush
[junit4] 2> 31523 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: index before flush _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1
[junit4] 2> 31524 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: startFullFlush
[junit4] 2> 31524 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 31524 T129 C12 oasu.LoggingInfoStream.message
[DWFC][qtp46220050-129]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=_3, aborting=false, numDocsInRAM=1,
deleteQueue=DWDQ: [ generation: 7 ]]
[junit4] 2> 31524 T129 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-129]: flush postings as segment _3 numDocs=1
[junit4] 2> 31525 T129 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-129]: new segment has 0 deleted docs
[junit4] 2> 31526 T129 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-129]: new segment has no vectors; no norms; no docValues; no
prox; no freqs
[junit4] 2> 31526 T129 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-129]: flushedFiles=[_3.fdx, _3_Lucene41_0.doc,
_3_Lucene41_0.tim, _3_Lucene41_0.tip, _3.fnm, _3.fdt]
[junit4] 2> 31526 T129 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-129]: flushed codec=Lucene410
[junit4] 2> 31526 T129 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-129]: flushed: segment=_3 ramUsed=0.063 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=1,456.356
[junit4] 2> 31527 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: publishFlushedSegment seg-private updates=null
[junit4] 2> 31527 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: publishFlushedSegment
[junit4] 2> 31527 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: push deletes 1 deleted terms (unique count=1)
bytesUsed=1056 delGen=14 packetCount=1 totBytesUsed=1056
[junit4] 2> 31528 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: publish sets newSegment delGen=15 seg=_3(4.11.0):C1
[junit4] 2> 31528 T129 C12 oasu.LoggingInfoStream.message
[IFD][qtp46220050-129]: now checkpoint "_0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1" [4 segments ; isCommit = false]
[junit4] 2> 31528 T129 C12 oasu.LoggingInfoStream.message
[IFD][qtp46220050-129]: 0 msec to checkpoint
[junit4] 2> 31528 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: apply all deletes during flush
[junit4] 2> 31529 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: applyDeletes: infos=[_0(4.11.0):C1, _1(4.11.0):C1,
_2(4.11.0):C1, _3(4.11.0):C1] packetCount=1
[junit4] 2> 31529 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: seg=_2(4.11.0):C1 segGen=12 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31529 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: seg=_1(4.11.0):C1 segGen=12 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31529 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: seg=_0(4.11.0):C1 segGen=12 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31530 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: applyDeletes took 2 msec
[junit4] 2> 31530 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: prune sis=segments_4: _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1 minGen=16 packetCount=1
[junit4] 2> 31530 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 31530 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: qtp46220050-129 finishFullFlush success=true
[junit4] 2> 31530 T129 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-129]: findMerges: 4 segments
[junit4] 2> 31531 T129 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-129]: seg=_0(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31531 T129 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-129]: seg=_1(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31531 T129 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-129]: seg=_2(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31532 T129 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-129]: seg=_3(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31532 T129 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-129]: allowedSegmentCount=1 vs count=4 (eligible count=4)
tooBigCount=0
[junit4] 2> 31532 T129 C12 oasu.LoggingInfoStream.message
[CMS][qtp46220050-129]: now merge
[junit4] 2> 31532 T129 C12 oasu.LoggingInfoStream.message
[CMS][qtp46220050-129]: index: _0(4.11.0):C1 _1(4.11.0):C1 _2(4.11.0):C1
_3(4.11.0):C1
[junit4] 2> 31532 T129 C12 oasu.LoggingInfoStream.message
[CMS][qtp46220050-129]: no more merges pending; now return
[junit4] 2> 31532 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: startCommit(): start
[junit4] 2> 31533 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: startCommit index=_0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1 changeCount=12
[junit4] 2> 31533 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: done all syncs: [_0_Lucene41_0.tip, _3.fdx, _3.si,
_0_Lucene41_0.tim, _1.si, _3_Lucene41_0.doc, _2.fdt, _2_Lucene41_0.doc, _1.fnm,
_2.fdx, _2.fnm, _1.fdx, _1_Lucene41_0.doc, _1.fdt, _0_Lucene41_0.doc, _0.si,
_2.si, _3_Lucene41_0.tim, _2_Lucene41_0.tip, _2_Lucene41_0.tim,
_3_Lucene41_0.tip, _0.fdx, _3.fnm, _0.fdt, _1_Lucene41_0.tip,
_1_Lucene41_0.tim, _3.fdt, _0.fnm]
[junit4] 2> 31534 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: commit: pendingCommit != null
[junit4] 2> 31534 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: commit: wrote segments file "segments_5"
[junit4] 2> 31534 T129 C12 oasu.LoggingInfoStream.message
[IFD][qtp46220050-129]: now checkpoint "_0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1" [4 segments ; isCommit = true]
[junit4] 2> 31534 T129 C12 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_4,generation=4}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@3cfd901d
lockFactory=NativeFSLockFactory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index),segFN=segments_5,generation=5}
[junit4] 2> 31535 T129 C12 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 5
[junit4] 2> 31535 T129 C12 oasu.LoggingInfoStream.message
[IFD][qtp46220050-129]: deleteCommits: now decRef commit "segments_4"
[junit4] 2> 31535 T129 C12 oasu.LoggingInfoStream.message
[IFD][qtp46220050-129]: delete "segments_4"
[junit4] 2> 31535 T129 C12 oasu.LoggingInfoStream.message
[IFD][qtp46220050-129]: 1 msec to checkpoint
[junit4] 2> 31536 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: commit: took 12.4 msec
[junit4] 2> 31536 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: commit: done
[junit4] 2> 31536 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31536 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 31536 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: flush at getReader
[junit4] 2> 31537 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: startFullFlush
[junit4] 2> 31537 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31537 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: apply all deletes during flush
[junit4] 2> 31537 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: applyDeletes: no deletes; skipping
[junit4] 2> 31537 T129 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-129]: prune sis=segments_5: _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1 minGen=16 packetCount=0
[junit4] 2> 31538 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: return reader version=9
reader=StandardDirectoryReader(segments_5:9:nrt _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1)
[junit4] 2> 31539 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: qtp46220050-129 finishFullFlush success=true
[junit4] 2> 31539 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: getReader took 3 msec
[junit4] 2> 31539 T129 C12 oass.SolrIndexSearcher.<init> Opening
Searcher@4bcdc5f0[collection1] main
[junit4] 2> 31540 T129 C12 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 31540 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@4bcdc5f0[collection1]
main{StandardDirectoryReader(segments_5:9:nrt _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1)}
[junit4] 2> 31540 T133 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 31540 T133 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@4bcdc5f0[collection1]
main{StandardDirectoryReader(segments_5:9:nrt _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1)}
[junit4] 2> 31767 T129 C12 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 31767 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31768 T129 C12 oasu.DirectUpdateHandler2.commit No uncommitted
changes. Skipping IW.commit.
[junit4] 2> 31768 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31768 T129 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-129]: nrtIsCurrent: infoVersion matches: true; DW changes:
false; BD changes: false
[junit4] 2> 31769 T129 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-129]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31769 T129 C12 oasc.SolrCore.openNewSearcher SolrIndexSearcher
has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 31769 T129 C12 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 31769 T129 C12 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=false&commit=true&softCommit=false&wt=javabin&version=2}
{add=[45 (1479244848478289920)],commit=,commit=} 0 252
[junit4] 2> 31774 T127 C12 oasu.DirectUpdateHandler2.commit start
commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 31775 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: forceMerge: index now _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1
[junit4] 2> 31775 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: now flush at forceMerge
[junit4] 2> 31775 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: start flush: applyAllDeletes=true
[junit4] 2> 31775 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: index before flush _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1
[junit4] 2> 31775 T127 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: startFullFlush
[junit4] 2> 31776 T127 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 31776 T127 C12 oasu.LoggingInfoStream.message
[DWFC][qtp46220050-127]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=_4, aborting=false, numDocsInRAM=1,
deleteQueue=DWDQ: [ generation: 9 ]]
[junit4] 2> 31776 T127 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: flush postings as segment _4 numDocs=1
[junit4] 2> 31777 T127 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: new segment has 0 deleted docs
[junit4] 2> 31777 T127 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: new segment has no vectors; no norms; no docValues; no
prox; no freqs
[junit4] 2> 31777 T127 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: flushedFiles=[_4.fdt, _4.fnm, _4_Lucene41_0.tip,
_4_Lucene41_0.doc, _4_Lucene41_0.tim, _4.fdx]
[junit4] 2> 31777 T127 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: flushed codec=Lucene410
[junit4] 2> 31778 T127 C12 oasu.LoggingInfoStream.message
[DWPT][qtp46220050-127]: flushed: segment=_4 ramUsed=0.063 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=1,456.356
[junit4] 2> 31778 T127 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: publishFlushedSegment seg-private updates=null
[junit4] 2> 31778 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: publishFlushedSegment
[junit4] 2> 31778 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: push deletes 1 deleted terms (unique count=1)
bytesUsed=1056 delGen=18 packetCount=1 totBytesUsed=1056
[junit4] 2> 31779 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: publish sets newSegment delGen=19 seg=_4(4.11.0):C1
[junit4] 2> 31779 T127 C12 oasu.LoggingInfoStream.message
[IFD][qtp46220050-127]: now checkpoint "_0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1 _4(4.11.0):C1" [5 segments ; isCommit = false]
[junit4] 2> 31779 T127 C12 oasu.LoggingInfoStream.message
[IFD][qtp46220050-127]: 0 msec to checkpoint
[junit4] 2> 31779 T127 C12 oasu.LoggingInfoStream.message
[DW][qtp46220050-127]: qtp46220050-127 finishFullFlush success=true
[junit4] 2> 31780 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: apply all deletes during flush
[junit4] 2> 31780 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: applyDeletes: infos=[_0(4.11.0):C1, _1(4.11.0):C1,
_2(4.11.0):C1, _3(4.11.0):C1, _4(4.11.0):C1] packetCount=1
[junit4] 2> 31780 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: seg=_3(4.11.0):C1 segGen=16 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31780 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: seg=_2(4.11.0):C1 segGen=16 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31781 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: seg=_1(4.11.0):C1 segGen=16 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31781 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: seg=_0(4.11.0):C1 segGen=16 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=0
[junit4] 2> 31781 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: applyDeletes took 1 msec
[junit4] 2> 31781 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: prune sis=segments_5: _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1 _4(4.11.0):C1 minGen=20 packetCount=1
[junit4] 2> 31781 T127 C12 oasu.LoggingInfoStream.message
[BD][qtp46220050-127]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 31782 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: findMerges: 5 segments
[junit4] 2> 31782 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: seg=_0(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31782 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: seg=_1(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31783 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: seg=_2(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31783 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: seg=_3(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31783 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: seg=_4(4.11.0):C1 size=0.001 MB [floored]
[junit4] 2> 31783 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: allowedSegmentCount=1 vs count=5 (eligible count=5)
tooBigCount=0
[junit4] 2> 31783 T127 C12 oasu.LoggingInfoStream.message
[CMS][qtp46220050-127]: now merge
[junit4] 2> 31784 T127 C12 oasu.LoggingInfoStream.message
[CMS][qtp46220050-127]: index: _0(4.11.0):C1 _1(4.11.0):C1 _2(4.11.0):C1
_3(4.11.0):C1 _4(4.11.0):C1
[junit4] 2> 31784 T127 C12 oasu.LoggingInfoStream.message
[CMS][qtp46220050-127]: no more merges pending; now return
[junit4] 2> 31784 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: findForcedMerges maxSegmentCount=1 infos=_0(4.11.0):C1
_1(4.11.0):C1 _2(4.11.0):C1 _3(4.11.0):C1 _4(4.11.0):C1
segmentsToMerge={_1(4.11.0):C1=true, _0(4.11.0):C1=true, _3(4.11.0):C1=true,
_2(4.11.0):C1=true, _4(4.11.0):C1=true}
[junit4] 2> 31784 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: eligible=[_0(4.11.0):C1, _1(4.11.0):C1, _2(4.11.0):C1,
_3(4.11.0):C1, _4(4.11.0):C1]
[junit4] 2> 31784 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: forceMergeRunning=false
[junit4] 2> 31785 T127 C12 oasu.LoggingInfoStream.message
[TMP][qtp46220050-127]: add final merge=_0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1 _4(4.11.0):C1
[junit4] 2> 31785 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: add merge to pendingMerges: _0(4.11.0):C1 _1(4.11.0):C1
_2(4.11.0):C1 _3(4.11.0):C1 _4(4.11.0):C1 [total 1 pending]
[junit4] 2> 31785 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: registerMerge merging= []
[junit4] 2> 31785 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: registerMerge info=_0(4.11.0):C1
[junit4] 2> 31785 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: registerMerge info=_1(4.11.0):C1
[junit4] 2> 31785 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: registerMerge info=_2(4.11.0):C1
[junit4] 2> 31786 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: registerMerge info=_3(4.11.0):C1
[junit4] 2> 31786 T127 C12 oasu.LoggingInfoStream.message
[IW][qtp46220050-127]: registerMerge info=_4(4.11.0):C1
[junit4] 2> 31786 T127 C12
[...truncated too long message...]
asu.LoggingInfoStream.message
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
startFullFlush
[junit4] 2> 49454 T122 C19 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
anyChanges? numDocsInRam=0 deletes=false hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 49454 T122 C19 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker
finishFullFlush success=true
[junit4] 2> 49454 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
apply all deletes during flush
[junit4] 2> 49454 T122 C19 oasu.LoggingInfoStream.message
[BD][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
applyDeletes: no deletes; skipping
[junit4] 2> 49454 T122 C19 oasu.LoggingInfoStream.message
[BD][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
prune sis=segments_21: _23(4.11.0):C10 minGen=338 packetCount=0
[junit4] 2> 49455 T122 C19 oasu.LoggingInfoStream.message
[CMS][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]: now
merge
[junit4] 2> 49455 T122 C19 oasu.LoggingInfoStream.message
[CMS][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
index: _23(4.11.0):C10
[junit4] 2> 49455 T122 C19 oasu.LoggingInfoStream.message
[CMS][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
no more merges pending; now return
[junit4] 2> 49455 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
waitForMerges
[junit4] 2> 49456 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
waitForMerges done
[junit4] 2> 49456 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
commit: start
[junit4] 2> 49456 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
commit: enter lock
[junit4] 2> 49456 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
commit: now prepare
[junit4] 2> 49457 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
prepareCommit: flush
[junit4] 2> 49457 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
index before flush _23(4.11.0):C10
[junit4] 2> 49457 T122 C19 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
startFullFlush
[junit4] 2> 49458 T122 C19 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
anyChanges? numDocsInRam=0 deletes=false hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 49458 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
apply all deletes during flush
[junit4] 2> 49459 T122 C19 oasu.LoggingInfoStream.message
[BD][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
applyDeletes: no deletes; skipping
[junit4] 2> 49460 T122 C19 oasu.LoggingInfoStream.message
[BD][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
prune sis=segments_21: _23(4.11.0):C10 minGen=338 packetCount=0
[junit4] 2> 49460 T122 C19 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker
finishFullFlush success=true
[junit4] 2> 49460 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
startCommit(): start
[junit4] 2> 49461 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
skip startCommit(): no changes pending
[junit4] 2> 49461 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
commit: pendingCommit == null; skip
[junit4] 2> 49461 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
commit: done
[junit4] 2> 49461 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
rollback
[junit4] 2> 49462 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]: all
running merges have aborted
[junit4] 2> 49462 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
rollback: done finish merges
[junit4] 2> 49462 T122 C19 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]: abort
[junit4] 2> 49463 T122 C19 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]: done
abort; abortedFiles=[] success=true
[junit4] 2> 49463 T122 C19 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]:
rollback: infos=_23(4.11.0):C10
[junit4] 2> 49463 T122 C19 oasu.LoggingInfoStream.message
[IFD][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]: now
checkpoint "_23(4.11.0):C10" [1 segments ; isCommit = false]
[junit4] 2> 49463 T122 C19 oasu.LoggingInfoStream.message
[IFD][SUITE-SolrExampleStreamingBinaryTest-seed#[4E1263FB5C7A9655]-worker]: 0
msec to checkpoint
[junit4] 2> 49464 T122 oasc.SolrCore.closeSearcher [collection1] Closing
main searcher on request.
[junit4] 2> 49465 T122 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 49465 T122 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index;done=false>>]
[junit4] 2> 49465 T122 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001/index
[junit4] 2> 49466 T122 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001;done=false>>]
[junit4] 2> 49466 T122 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001/tempDir-001
[junit4] 2> 49466 T122 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 54440 T122 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/./temp/solr.client.solrj.embedded.SolrExampleStreamingBinaryTest-4E1263FB5C7A9655-001
[junit4] 2> NOTE: test params are: codec=Lucene42,
sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=el_CY,
timezone=America/Los_Angeles
[junit4] 2> NOTE: Linux 3.13.0-35-generic amd64/Oracle Corporation
1.9.0-ea (64-bit)/cpus=8,threads=1,free=80231128,total=127729664
[junit4] 2> NOTE: All tests run in this JVM:
[ConcurrentUpdateSolrServerTest, TestCoreAdmin, TestUpdateRequestCodec,
SolrParamTest, JettyWebappTest, TestEmbeddedSolrServer, LargeVolumeJettyTest,
SolrExampleStreamingBinaryTest]
[junit4] Completed on J0 in 23.73s, 29 tests, 1 error <<< FAILURES!
[...truncated 109 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:491: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:471: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:60: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:202: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:494: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1360:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:963:
There were test failures: 53 suites, 313 tests, 1 error
Total time: 97 minutes 49 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.9.0-ea-b28
-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
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]
