Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11208/
Java: 32bit/jdk1.8.0_40-ea-b04 -server -XX:+UseG1GC
2 tests failed.
FAILED:
org.apache.solr.client.solrj.SolrExampleBinaryTest.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([C7D57CBE21150A8C:B40F6324AD0D7D8A]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:530)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
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:483)
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.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)
FAILED: org.apache.solr.client.solrj.SolrExampleXMLTest.testChildDoctransformer
Error Message:
java.lang.AssertionError
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([C7D57CBE21150A8C:B40F6324AD0D7D8A]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:568)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
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:483)
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.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 12477 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrExampleBinaryTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-C7D57CBE21150A8C-001/init-core-data-001
[junit4] 2> 122290 T232 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 122291 T232 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 122292 T232 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 122316 T232 oejs.AbstractConnector.doStart Started
[email protected]:41073
[junit4] 2> 122318 T232 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 122318 T232 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 122319 T232 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr
[junit4] 2> 122319 T232 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/'
[junit4] 2> 122335 T232 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/solr.xml
[junit4] 2> 122350 T232 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr
[junit4] 2> 122350 T232 oasc.CoreContainer.<init> New CoreContainer
16119088
[junit4] 2> 122351 T232 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/]
[junit4] 2> 122352 T232 oashc.HttpShardHandlerFactory.getParameter Setting
socketTimeout to: 0
[junit4] 2> 122352 T232 oashc.HttpShardHandlerFactory.getParameter Setting
urlScheme to: null
[junit4] 2> 122352 T232 oashc.HttpShardHandlerFactory.getParameter Setting
connTimeout to: 0
[junit4] 2> 122352 T232 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnectionsPerHost to: 20
[junit4] 2> 122353 T232 oashc.HttpShardHandlerFactory.getParameter Setting
corePoolSize to: 0
[junit4] 2> 122353 T232 oashc.HttpShardHandlerFactory.getParameter Setting
maximumPoolSize to: 2147483647
[junit4] 2> 122353 T232 oashc.HttpShardHandlerFactory.getParameter Setting
maxThreadIdleTime to: 5
[junit4] 2> 122354 T232 oashc.HttpShardHandlerFactory.getParameter Setting
sizeOfQueue to: -1
[junit4] 2> 122354 T232 oashc.HttpShardHandlerFactory.getParameter Setting
fairnessPolicy to: false
[junit4] 2> 122355 T232 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 122355 T232 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 122355 T232 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 122356 T232 oasc.CoreContainer.load Host Name:
[junit4] 2> 122358 T232 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr
[junit4] 2> 122362 T232 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/
[junit4] 2> 122365 T232 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 122366 T242 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/'
[junit4] 2> 122394 T242 oasc.SolrConfig.initLibs Adding specified lib dirs
to ClassLoader
[junit4] 2> 122395 T242 oasc.SolrResourceLoader.addToClassLoader WARN No
files added to classloader from lib: ../../../contrib/extraction/lib (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4] 2> 122395 T242 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: ../../../dist/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 122395 T242 oasc.SolrResourceLoader.addToClassLoader WARN No
files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4] 2> 122396 T242 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: ../../../dist/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 122396 T242 oasc.SolrResourceLoader.addToClassLoader WARN No
files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4] 2> 122396 T242 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: ../../../dist/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 122397 T242 oasc.SolrResourceLoader.addToClassLoader WARN No
files added to classloader from lib: ../../../contrib/velocity/lib (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4] 2> 122397 T242 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: ../../../dist/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 2> 122422 T242 oasu.SolrIndexConfig.<init> IndexWriter infoStream
solr logging is enabled
[junit4] 2> 122425 T242 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 122479 T242 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 122480 T242 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 122496 T242 oass.IndexSchema.readSchema [collection1] Schema
name=example
[junit4] 2> 122769 T242 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 122771 T242 oasr.RestManager$Registry.registerManagedResource
Registered ManagedResource impl
org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path
/schema/analysis/stopwords/english
[junit4] 2> 122772 T242 oasr.RestManager$Registry.registerManagedResource
Registered ManagedResource impl
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager
for path /schema/analysis/synonyms/english
[junit4] 2> 122791 T242 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 122795 T242 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 122825 T242 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from instancedir
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/
[junit4] 2> 122825 T242 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 122825 T242 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-C7D57CBE21150A8C-001/tempDir-001/
[junit4] 2> 122826 T242 oasc.JmxMonitoredMap.<init> No JMX servers found,
not exposing Solr information with JMX.
[junit4] 2> 122826 T242 oasc.SolrCore.initListeners [collection1] Added
SolrEventListener for newSearcher:
org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 122826 T242 oasc.SolrCore.initListeners [collection1] Added
SolrEventListener for firstSearcher:
org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher
warming in solrconfig.xml}]}
[junit4] 2> 122827 T242 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-C7D57CBE21150A8C-001/tempDir-001
[junit4] 2> 122827 T242 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-C7D57CBE21150A8C-001/tempDir-001/index/
[junit4] 2> 122828 T242 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-C7D57CBE21150A8C-001/tempDir-001/index'
doesn't exist. Creating new index...
[junit4] 2> 122828 T242 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-C7D57CBE21150A8C-001/tempDir-001/index
[junit4] 2> 122829 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: init: current segments file is "null";
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1e980d6
[junit4] 2> 122829 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 122829 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: 0 msec to checkpoint
[junit4] 2> 122829 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: init: create=true
[junit4] 2> 122830 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory(id=lucene-b5060f))
[junit4] 2> index=
[junit4] 2> version=5.0.0
[junit4] 2> analyzer=null
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> maxBufferedDeleteTerms=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2>
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=CREATE
[junit4] 2>
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler:
maxThreadCount=1, maxMergeCount=2, mergeThreadPriority=-1
[junit4] 2> default WRITE_LOCK_TIMEOUT=1000
[junit4] 2> writeLockTimeout=1000
[junit4] 2> codec=Lucene50
[junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream
[junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10,
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0,
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2>
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@1b0609a
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> checkIntegrityAtMerge=false
[junit4] 2> commitOnClose=true
[junit4] 2> writer=org.apache.lucene.util.SetOnce@1c87be1
[junit4] 2>
[junit4] 2> 122830 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: now flush at close
[junit4] 2> 122830 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 122830 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: index before flush
[junit4] 2> 122831 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: startFullFlush
[junit4] 2> 122831 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 122831 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: coreLoadExecutor-139-thread-1
finishFullFlush success=true
[junit4] 2> 122831 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: apply all deletes during flush
[junit4] 2> 122831 T242 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-139-thread-1]: prune sis=null:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 122832 T242 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-139-thread-1]: now merge
[junit4] 2> 122832 T242 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-139-thread-1]: index:
[junit4] 2> 122832 T242 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-139-thread-1]: no more merges pending; now return
[junit4] 2> 122832 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: waitForMerges
[junit4] 2> 122832 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: waitForMerges done
[junit4] 2> 122832 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: commit: start
[junit4] 2> 122833 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: commit: enter lock
[junit4] 2> 122833 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: commit: now prepare
[junit4] 2> 122833 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: prepareCommit: flush
[junit4] 2> 122833 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: index before flush
[junit4] 2> 122833 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: startFullFlush
[junit4] 2> 122834 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 122834 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: apply all deletes during flush
[junit4] 2> 122834 T242 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-139-thread-1]: prune sis=null:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 122834 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: coreLoadExecutor-139-thread-1
finishFullFlush success=true
[junit4] 2> 122834 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: startCommit(): start
[junit4] 2> 122835 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: startCommit index= changeCount=1
[junit4] 2> 122835 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: done all syncs: []
[junit4] 2> 122836 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: commit: pendingCommit != null
[junit4] 2> 122836 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: now checkpoint "" [0 segments ; isCommit
= true]
[junit4] 2> 122836 T242 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-b5060f)),segFN=segments_1,generation=1}
[junit4] 2> 122836 T242 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 122836 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: 0 msec to checkpoint
[junit4] 2> 122837 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: commit: wrote segments file "segments_1"
[junit4] 2> 122837 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: commit: took 3.7 msec
[junit4] 2> 122837 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: commit: done
[junit4] 2> 122837 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: rollback
[junit4] 2> 122837 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: all running merges have aborted
[junit4] 2> 122838 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: rollback: done finish merges
[junit4] 2> 122838 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: abort
[junit4] 2> 122838 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: done abort; abortedFiles=[] success=true
[junit4] 2> 122838 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: rollback: infos=
[junit4] 2> 122838 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 122839 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: 0 msec to checkpoint
[junit4] 2> 122839 T242 oasc.SolrCore.initWriters created json:
solr.JSONResponseWriter
[junit4] 2> 122839 T242 oasc.SolrCore.initWriters adding lazy
queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 122839 T242 oasc.SolrCore.initWriters created velocity:
solr.VelocityResponseWriter
[junit4] 2> 122840 T242 oasc.SolrCore.initWriters created xslt:
solr.XSLTResponseWriter
[junit4] 2> 122840 T242 oasr.XSLTResponseWriter.init
xsltCacheLifetimeSeconds=5
[junit4] 2> 122841 T242 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 122841 T242 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 122841 T242 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 122842 T242 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 122842 T242 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 122842 T242 oasc.RequestHandlers.initHandlersFromConfig
created /select: solr.SearchHandler
[junit4] 2> 122842 T242 oasc.RequestHandlers.initHandlersFromConfig
created /query: solr.SearchHandler
[junit4] 2> 122842 T242 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 122843 T242 oasc.RequestHandlers.initHandlersFromConfig
created /export: solr.SearchHandler
[junit4] 2> 122843 T242 oasc.RequestHandlers.initHandlersFromConfig
created /browse: solr.SearchHandler
[junit4] 2> 122843 T242 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 2> 122843 T242 oasc.RequestHandlers.initHandlersFromConfig
created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 2> 122843 T242 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 122844 T242 oasc.RequestHandlers.initHandlersFromConfig
created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 122844 T242 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 122844 T242 oasc.RequestHandlers.initHandlersFromConfig
created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 122844 T242 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: solr.admin.AdminHandlers
[junit4] 2> 122844 T242 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: solr.PingRequestHandler
[junit4] 2> 122845 T242 oasc.RequestHandlers.initHandlersFromConfig
created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 122845 T242 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 122845 T242 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 122845 T242 oasc.RequestHandlers.initHandlersFromConfig
created /spell: solr.SearchHandler
[junit4] 2> 122845 T242 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 122846 T242 oasc.RequestHandlers.initHandlersFromConfig
created /suggest: solr.SearchHandler
[junit4] 2> 122846 T242 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 122846 T242 oasc.RequestHandlers.initHandlersFromConfig
created /tvrh: solr.SearchHandler
[junit4] 2> 122846 T242 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 122846 T242 oasc.RequestHandlers.initHandlersFromConfig
created /terms: solr.SearchHandler
[junit4] 2> 122846 T242 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 122847 T242 oasc.RequestHandlers.initHandlersFromConfig
created /elevate: solr.SearchHandler
[junit4] 2> 122849 T242 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122850 T242 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122851 T242 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122852 T242 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 122856 T242 oasu.CommitTracker.<init> Hard AutoCommit: if
uncommited for 15000ms;
[junit4] 2> 122856 T242 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 122857 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: init: current segments file is
"segments_1";
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1e980d6
[junit4] 2> 122858 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: init: load commit "segments_1"
[junit4] 2> 122858 T242 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-b5060f)),segFN=segments_1,generation=1}
[junit4] 2> 122858 T242 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 122858 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 122859 T242 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-139-thread-1]: 0 msec to checkpoint
[junit4] 2> 122859 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: init: create=false
[junit4] 2> 122859 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory(id=lucene-b5060f))
[junit4] 2> index=
[junit4] 2> version=5.0.0
[junit4] 2> analyzer=null
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> maxBufferedDeleteTerms=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2>
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=APPEND
[junit4] 2>
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler:
maxThreadCount=1, maxMergeCount=2, mergeThreadPriority=-1
[junit4] 2> default WRITE_LOCK_TIMEOUT=1000
[junit4] 2> writeLockTimeout=1000
[junit4] 2> codec=Lucene50
[junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream
[junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10,
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0,
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2>
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@1915f75
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> checkIntegrityAtMerge=false
[junit4] 2> commitOnClose=true
[junit4] 2> writer=org.apache.lucene.util.SetOnce@1884949
[junit4] 2>
[junit4] 2> 122860 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: flush at getReader
[junit4] 2> 122860 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: startFullFlush
[junit4] 2> 122860 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 122860 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: apply all deletes during flush
[junit4] 2> 122860 T242 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-139-thread-1]: prune sis=segments_1:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 122861 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: return reader version=1
reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 122861 T242 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-139-thread-1]: coreLoadExecutor-139-thread-1
finishFullFlush success=true
[junit4] 2> 122861 T242 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-139-thread-1]: getReader took 1 msec
[junit4] 2> 122861 T242 oass.SolrIndexSearcher.<init> Opening
Searcher@1d5bab5[collection1] main
[junit4] 2> 122862 T242 oasr.ManagedResourceStorage.newStorageIO WARN
Cannot write to config directory
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 122862 T242 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 122862 T242 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using InMemoryStorage
[junit4] 2> 122862 T242 oasr.ManagedResource.reloadFromStorage WARN No
stored data found for /rest/managed
[junit4] 2> 122863 T242 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 122863 T242 oasr.RestManager.init Initializing 2 registered
ManagedResources
[junit4] 2> 122863 T242 oasr.ManagedResourceStorage.load Reading
_schema_analysis_stopwords_english.json using InMemoryStorage
[junit4] 2> 122863 T242 oasr.ManagedResource.reloadFromStorage WARN No
stored data found for /schema/analysis/stopwords/english
[junit4] 2> 122863 T242 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _schema_analysis_stopwords_english.json using
InMemoryStorage
[junit4] 2> 122864 T242
oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for
/schema/analysis/stopwords/english
[junit4] 2> 122864 T242 oasr.ManagedResource.notifyObserversDuringInit
Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 122864 T242 oasr.ManagedResourceStorage.load Reading
_schema_analysis_synonyms_english.json using InMemoryStorage
[junit4] 2> 122864 T242 oasr.ManagedResource.reloadFromStorage WARN No
stored data found for /schema/analysis/synonyms/english
[junit4] 2> 122864 T242
oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage
Loaded 0 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 122865 T242 oasr.ManagedResource.notifyObserversDuringInit
Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 122865 T242 oashc.SpellCheckComponent.inform Initializing
spell checkers
[junit4] 2> 122865 T242 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> 122866 T242 oashc.SpellCheckComponent.inform No queryConverter
defined, using default converter
[junit4] 2> 122866 T242 oashc.SuggestComponent.inform Initializing
SuggestComponent
[junit4] 2> 122866 T242 oasss.SolrSuggester.init init:
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
[junit4] 2> 122866 T242 oasss.SolrSuggester.init Dictionary loaded with
params:
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
[junit4] 2> 122867 T242 oashc.QueryElevationComponent.inform Loading
QueryElevation from:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4] 2> 122872 T242 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 122873 T243 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@1d5bab5[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 122873 T242 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 122874 T232 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1
[junit4] 2> 122874 T232 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 122874 T232 oas.SolrJettyTestBase.createJetty Jetty Assigned
Port#41073
[junit4] 2> 122883 T232 oas.SolrTestCaseJ4.setUp ###Starting
testChildDoctransformer
[junit4] 2> ASYNC NEW_CORE C157 name=collection1
org.apache.solr.core.SolrCore@191bc93
[junit4] 2> 122884 T243 C157 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=11
[junit4] 2> 122885 T243 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 122885 T243
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index
for spellchecker: default
[junit4] 2> 122885 T243
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index
for spellchecker: wordbreak
[junit4] 2> 122885 T243
oashc.SuggestComponent$SuggesterListener.newSearcher Loading suggester index
for: mySuggester
[junit4] 2> 122885 T243 oasss.SolrSuggester.reload reload()
[junit4] 2> 122885 T243 oasss.SolrSuggester.build build()
[junit4] 2> 122888 T241 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 122889 T241 C157 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: nrtIsCurrent: infoVersion matches: true; DW changes:
true; BD changes: false
[junit4] 2> 122889 T241 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 122889 T241 C157 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: flush at getReader
[junit4] 2> 122890 T241 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: startFullFlush
[junit4] 2> 122890 T241 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 122890 T241 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: qtp32896926-241: flush naked frozen global deletes
[junit4] 2> 122891 T241 C157 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: push deletes 1 deleted queries bytesUsed=64 delGen=2
packetCount=1 totBytesUsed=64
[junit4] 2> 122891 T241 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: flush: push buffered updates: 1 deleted queries
bytesUsed=64
[junit4] 2> 122891 T241 C157 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: apply all deletes during flush
[junit4] 2> 122892 T241 C157 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: prune sis=segments_1: minGen=9223372036854775807
packetCount=1
[junit4] 2> 122892 T243 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1d5bab5[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 122892 T241 C157 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 122893 T241 C157 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: return reader version=1
reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 122893 T241 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: qtp32896926-241 finishFullFlush success=true
[junit4] 2> 122893 T241 C157 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: getReader took 4 msec
[junit4] 2> 122894 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*
(-1480739988858470400)} 0 9
[junit4] 2> 122939 T239 C157 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 122940 T239 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-239]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 122940 T239 C157 oasu.DirectUpdateHandler2.commit No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 122941 T239 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-239]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 122941 T239 C157 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: nrtIsCurrent: infoVersion matches: true; DW changes:
false; BD changes: false
[junit4] 2> 122941 T239 C157 oasu.LoggingInfoStream.message
[DW][qtp32896926-239]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 122941 T239 C157 oasc.SolrCore.openNewSearcher
SolrIndexSearcher has not changed - not re-opening:
org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 122942 T239 C157 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 122942 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 3
[junit4] 2> 122981 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[0
(1480739988921384960)]} 0 35
[junit4] 2> 122985 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[185
(1480739988960182272)]} 0 1
[junit4] 2> 122993 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[186
(1480739988963328000)]} 0 6
[junit4] 2> 122996 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[232
(1480739988972765184)]} 0 0
[junit4] 2> 123009 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[233
(1480739988975910912)]} 0 11
[junit4] 2> 123019 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[310
(1480739988989542400)]} 0 8
[junit4] 2> 123033 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[378
(1480739989000028160)]} 0 11
[junit4] 2> 123036 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[470
(1480739989013659648)]} 0 1
[junit4] 2> 123052 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[471
(1480739989017853952)]} 0 14
[junit4] 2> 123061 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[619
(1480739989034631168)]} 0 6
[junit4] 2> 123073 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[683
(1480739989044068352)]} 0 10
[junit4] 2> 123094 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[799
(1480739989057699840)]} 0 18
[junit4] 2> 123097 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1056
(1480739989078671360)]} 0 0
[junit4] 2> 123101 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1057
(1480739989081817088)]} 0 2
[junit4] 2> 123125 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1079
(1480739989087059968)]} 0 21
[junit4] 2> 123128 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1405
(1480739989111177216)]} 0 0
[junit4] 2> 123131 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1406
(1480739989114322944)]} 0 0
[junit4] 2> 123135 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1407
(1480739989116420096)]} 0 2
[junit4] 2> 123146 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1408
(1480739989120614400)]} 0 9
[junit4] 2> 123153 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1560
(1480739989133197312)]} 0 4
[junit4] 2> 123164 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1631
(1480739989141585920)]} 0 8
[junit4] 2> 123170 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1744
(1480739989152071680)]} 0 4
[junit4] 2> 123189 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1793
(1480739989158363136)]} 0 16
[junit4] 2> 123205 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[2068
(1480739989179334656)]} 0 13
[junit4] 2> 123222 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[2328
(1480739989196111872)]} 0 14
[junit4] 2> 123229 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[2622
(1480739989212889088)]} 0 4
[junit4] 2> 123242 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[2693
(1480739989220229120)]} 0 11
[junit4] 2> 123244 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[2907
(1480739989232812032)]} 0 0
[junit4] 2> 123250 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[2908
(1480739989235957760)]} 0 3
[junit4] 2> 123254 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[2971
(1480739989241200640)]} 0 2
[junit4] 2> 123257 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3014
(1480739989246443520)]} 0 0
[junit4] 2> 123271 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3015
(1480739989249589248)]} 0 12
[junit4] 2> 123274 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3265
(1480739989264269312)]} 0 0
[junit4] 2> 123281 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3266
(1480739989267415040)]} 0 4
[junit4] 2> 123283 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3346
(1480739989273706496)]} 0 0
[junit4] 2> 123286 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3347
(1480739989276852224)]} 0 0
[junit4] 2> 123292 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3348
(1480739989278949376)]} 0 4
[junit4] 2> 123295 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3425
(1480739989285240832)]} 0 1
[junit4] 2> 123298 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3444
(1480739989289435136)]} 0 1
[junit4] 2> 123306 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3445
(1480739989291532288)]} 0 6
[junit4] 2> 123308 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3561
(1480739989299920896)]} 0 0
[junit4] 2> 123319 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3562
(1480739989303066624)]} 0 8
[junit4] 2> 123322 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3744
(1480739989314600960)]} 0 1
[junit4] 2> 123335 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3745
(1480739989317746688)]} 0 10
[junit4] 2> 123337 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3974
(1480739989330329600)]} 0 0
[junit4] 2> 123355 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[3975
(1480739989333475328)]} 0 15
[junit4] 2> 123358 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4288
(1480739989351301120)]} 0 1
[junit4] 2> 123360 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4289
(1480739989353398272)]} 0 1
[junit4] 2> 123377 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4290
(1480739989356544000)]} 0 15
[junit4] 2> 123380 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4622
(1480739989374369792)]} 0 1
[junit4] 2> 123382 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4623
(1480739989377515520)]} 0 1
[junit4] 2> 123389 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4624
(1480739989379612672)]} 0 5
[junit4] 2> 123394 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4730
(1480739989386952704)]} 0 3
[junit4] 2> 123396 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4776
(1480739989392195584)]} 0 0
[junit4] 2> 123400 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4777
(1480739989394292736)]} 0 2
[junit4] 2> 123402 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4814
(1480739989398487040)]} 0 0
[junit4] 2> 123404 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4815
(1480739989400584192)]} 0 0
[junit4] 2> 123417 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[4817
(1480739989403729920)]} 0 10
[junit4] 2> 123430 T239 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[5032
(1480739989416312832)]} 0 11
[junit4] 2> 123432 T238 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[5274
(1480739989429944320)]} 0 0
[junit4] 2> 123447 T240 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[5275
(1480739989433090048)]} 0 12
[junit4] 2> 123450 T241 C157 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[5564
(1480739989448818688)]} 0 0
[junit4] 2> ASYNC NEW_CORE C158 name=collection1
org.apache.solr.core.SolrCore@191bc93
[junit4] 2> 123465 T239 C158 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[5565
(1480739989462450176)]} 0 13
[junit4] 2> 123475 T238 C158 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[5566
(1480739989468741632)]} 0 6
[junit4] 2> 123483 T240 C158 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[5647
(1480739989477130240)]} 0 6
[junit4] 2> 123488 T241 C158 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 123488 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: commit: start
[junit4] 2> 123488 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: commit: enter lock
[junit4] 2> 123489 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: commit: now prepare
[junit4] 2> 123489 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: prepareCommit: flush
[junit4] 2> 123489 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: index before flush
[junit4] 2> 123489 T241 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: startFullFlush
[junit4] 2> 123489 T241 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: anyChanges? numDocsInRam=5766 deletes=true
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 123490 T241 C158 oasu.LoggingInfoStream.message
[DWFC][qtp32896926-241]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0 65 deleted terms (unique count=65) bytesUsed=9406,
segment=_0, aborting=false, numDocsInRAM=5766, deleteQueue=DWDQ: [ generation:
2 ]]
[junit4] 2> 123491 T241 C158 oasu.LoggingInfoStream.message
[DWPT][qtp32896926-241]: flush postings as segment _0 numDocs=5766
[junit4] 2> 123559 T241 C158 oasu.LoggingInfoStream.message
[DWPT][qtp32896926-241]: new segment has 0 deleted docs
[junit4] 2> 123559 T241 C158 oasu.LoggingInfoStream.message
[DWPT][qtp32896926-241]: new segment has no vectors; norms; no docValues; prox;
freqs
[junit4] 2> 123559 T241 C158 oasu.LoggingInfoStream.message
[DWPT][qtp32896926-241]: flushedFiles=[_0_Lucene41_0.tip, _0_Lucene41_0.doc,
_0_Lucene41_0.tim, _0.nvd, _0.fdx, _0.fdt, _0_Lucene41_0.pos, _0.nvm, _0.fnm]
[junit4] 2> 123559 T241 C158 oasu.LoggingInfoStream.message
[DWPT][qtp32896926-241]: flushed codec=Lucene50
[junit4] 2> 123560 T241 C158 oasu.LoggingInfoStream.message
[DWPT][qtp32896926-241]: flushed: segment=_0 ramUsed=0.337 MB
newFlushedSize(includes docstores)=0.105 MB docs/MB=54,790.115
[junit4] 2> 123560 T241 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: publishFlushedSegment seg-private updates=null
[junit4] 2> 123560 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: publishFlushedSegment
[junit4] 2> 123560 T241 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: push deletes 65 deleted terms (unique count=65)
bytesUsed=1056 delGen=4 packetCount=1 totBytesUsed=1056
[junit4] 2> 123561 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: publish sets newSegment delGen=5 seg=_0(5.0.0):C5766
[junit4] 2> 123561 T241 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-241]: now checkpoint "_0(5.0.0):C5766" [1 segments ; isCommit
= false]
[junit4] 2> 123561 T241 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-241]: 0 msec to checkpoint
[junit4] 2> 123562 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: apply all deletes during flush
[junit4] 2> 123562 T241 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: applyDeletes: infos=[_0(5.0.0):C5766] packetCount=1
[junit4] 2> 123562 T241 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: applyDeletes took 0 msec
[junit4] 2> 123562 T241 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: prune sis=segments_1: _0(5.0.0):C5766 minGen=6
packetCount=1
[junit4] 2> 123562 T241 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 123563 T241 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: qtp32896926-241 finishFullFlush success=true
[junit4] 2> 123563 T241 C158 oasu.LoggingInfoStream.message
[TMP][qtp32896926-241]: findMerges: 1 segments
[junit4] 2> 123563 T241 C158 oasu.LoggingInfoStream.message
[TMP][qtp32896926-241]: seg=_0(5.0.0):C5766 size=0.105 MB [floored]
[junit4] 2> 123564 T241 C158 oasu.LoggingInfoStream.message
[TMP][qtp32896926-241]: allowedSegmentCount=1 vs count=1 (eligible count=1)
tooBigCount=0
[junit4] 2> 123564 T241 C158 oasu.LoggingInfoStream.message
[CMS][qtp32896926-241]: now merge
[junit4] 2> 123564 T241 C158 oasu.LoggingInfoStream.message
[CMS][qtp32896926-241]: index: _0(5.0.0):C5766
[junit4] 2> 123564 T241 C158 oasu.LoggingInfoStream.message
[CMS][qtp32896926-241]: no more merges pending; now return
[junit4] 2> 123564 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: startCommit(): start
[junit4] 2> 123565 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: startCommit index=_0(5.0.0):C5766 changeCount=3
[junit4] 2> 123565 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: done all syncs: [_0_Lucene41_0.tip, _0_Lucene41_0.doc,
_0.si, _0_Lucene41_0.tim, _0.nvd, _0.fdx, _0.fdt, _0_Lucene41_0.pos, _0.nvm,
_0.fnm]
[junit4] 2> 123565 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: commit: pendingCommit != null
[junit4] 2> 123566 T241 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-241]: now checkpoint "_0(5.0.0):C5766" [1 segments ; isCommit
= true]
[junit4] 2> 123566 T241 C158 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-b5060f)),segFN=segments_1,generation=1}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-b5060f)),segFN=segments_2,generation=2}
[junit4] 2> 123566 T241 C158 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 2
[junit4] 2> 123567 T241 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-241]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 123567 T241 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-241]: delete "segments_1"
[junit4] 2> 123567 T241 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-241]: 1 msec to checkpoint
[junit4] 2> 123567 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: commit: wrote segments file "segments_2"
[junit4] 2> 123568 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: commit: took 78.7 msec
[junit4] 2> 123568 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: commit: done
[junit4] 2> 123568 T241 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 123568 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 123568 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: flush at getReader
[junit4] 2> 123569 T241 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: startFullFlush
[junit4] 2> 123569 T241 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 123569 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: apply all deletes during flush
[junit4] 2> 123569 T241 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: applyDeletes: no deletes; skipping
[junit4] 2> 123570 T241 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-241]: prune sis=segments_1: _0(5.0.0):C5766 minGen=6
packetCount=0
[junit4] 2> 123575 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: return reader version=3
reader=StandardDirectoryReader(segments_1:3:nrt _0(5.0.0):C5766)
[junit4] 2> 123575 T241 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-241]: qtp32896926-241 finishFullFlush success=true
[junit4] 2> 123576 T241 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-241]: getReader took 8 msec
[junit4] 2> 123576 T241 C158 oass.SolrIndexSearcher.<init> Opening
Searcher@d4f94b[collection1] main
[junit4] 2> 123577 T241 C158 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 123577 T243 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@d4f94b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C5766)))}
[junit4] 2> 123578 T243 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 123578 T243 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@d4f94b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C5766)))}
[junit4] 2> 123578 T241 C158 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 90
[junit4] 2> 123581 T240 C158 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5766
status=0 QTime=0
[junit4] 2> 123602 T239 C158 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={q=level_i:0&fl=*,[child+parentFilter%3D"level_i:0"]&wt=javabin&version=2}
hits=65 status=0 QTime=13
[junit4] 2> 123667 T241 C158 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={q=*:*&fl=id,[child+parentFilter%3D"level_i:4"+childFilter%3D"level_i:5"+limit%3D"24"]&fq=level_i:4&wt=javabin&version=2}
hits=3746 status=0 QTime=18
[junit4] 2> 123675 T238 C158 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={q=*:*&fl=id,[child+parentFilter%3D"level_i:0"+childFilter%3D"level_i:[0+TO+0]"+limit%3D"1"]&fq=level_i:0&wt=javabin&version=2}
hits=65 status=0 QTime=3
[junit4] 2> 123687 T238 C158 oasc.SolrException.log ERROR
null:java.lang.RuntimeException: java.lang.AssertionError
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.sendError(SolrDispatchFilter.java:797)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:432)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:202)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:137)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at
org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at
org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at
org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at
org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at
org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at
org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at
org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at
org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at
org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.AssertionError
[junit4] 2> at
org.apache.lucene.search.join.ToChildBlockJoinQuery$ToChildBlockJoinScorer.advance(ToChildBlockJoinQuery.java:286)
[junit4] 2> at
org.apache.lucene.search.FilteredQuery$LeapFrogScorer.advanceToNextCommonDoc(FilteredQuery.java:274)
[junit4] 2> at
org.apache.lucene.search.FilteredQuery$LeapFrogScorer.nextDoc(FilteredQuery.java:286)
[junit4] 2> at
org.apache.lucene.search.Weight$DefaultBulkScorer.scoreAll(Weight.java:191)
[junit4] 2> at
org.apache.lucene.search.Weight$DefaultBulkScorer.score(Weight.java:162)
[junit4] 2> at
org.apache.lucene.search.BulkScorer.score(BulkScorer.java:35)
[junit4] 2> at
org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:614)
[junit4] 2> at
org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:289)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.buildAndRunCollectorChain(SolrIndexSearcher.java:226)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1633)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1456)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:537)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.getDocList(SolrIndexSearcher.java:1291)
[junit4] 2> at
org.apache.solr.response.transform.ChildDocTransformer.transform(ChildDocTransformerFactory.java:144)
[junit4] 2> at
org.apache.solr.response.BinaryResponseWriter$Resolver.writeResultsBody(BinaryResponseWriter.java:159)
[junit4] 2> at
org.apache.solr.response.BinaryResponseWriter$Resolver.writeResults(BinaryResponseWriter.java:183)
[junit4] 2> at
org.apache.solr.response.BinaryResponseWriter$Resolver.resolve(BinaryResponseWriter.java:88)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.writeVal(JavaBinCodec.java:158)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.writeNamedList(JavaBinCodec.java:148)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.writeKnownType(JavaBinCodec.java:242)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.writeVal(JavaBinCodec.java:153)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:96)
[junit4] 2> at
org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:52)
[junit4] 2> at
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:50)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:771)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:424)
[junit4] 2> ... 26 more
[junit4] 2>
[junit4] 2> 123689 T238 oejs.ServletHandler.doHandle WARN Error for
/solr/collection1/select java.lang.AssertionError
[junit4] 2> at
org.apache.lucene.search.join.ToChildBlockJoinQuery$ToChildBlockJoinScorer.advance(ToChildBlockJoinQuery.java:286)
[junit4] 2> at
org.apache.lucene.search.FilteredQuery$LeapFrogScorer.advanceToNextCommonDoc(FilteredQuery.java:274)
[junit4] 2> at
org.apache.lucene.search.FilteredQuery$LeapFrogScorer.nextDoc(FilteredQuery.java:286)
[junit4] 2> at
org.apache.lucene.search.Weight$DefaultBulkScorer.scoreAll(Weight.java:191)
[junit4] 2> at
org.apache.lucene.search.Weight$DefaultBulkScorer.score(Weight.java:162)
[junit4] 2> at
org.apache.lucene.search.BulkScorer.score(BulkScorer.java:35)
[junit4] 2> at
org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:614)
[junit4] 2> at
org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:289)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.buildAndRunCollectorChain(SolrIndexSearcher.java:226)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1633)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1456)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:537)
[junit4] 2> at
org.apache.solr.search.SolrIndexSearcher.getDocList(SolrIndexSearcher.java:1291)
[junit4] 2> at
org.apache.solr.response.transform.ChildDocTransformer.transform(ChildDocTransformerFactory.java:144)
[junit4] 2> at
org.apache.solr.response.BinaryResponseWriter$Resolver.writeResultsBody(BinaryResponseWriter.java:159)
[junit4] 2> at
org.apache.solr.response.BinaryResponseWriter$Resolver.writeResults(BinaryResponseWriter.java:183)
[junit4] 2> at
org.apache.solr.response.BinaryResponseWriter$Resolver.resolve(BinaryResponseWriter.java:88)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.writeVal(JavaBinCodec.java:158)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.writeNamedList(JavaBinCodec.java:148)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.writeKnownType(JavaBinCodec.java:242)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.writeVal(JavaBinCodec.java:153)
[junit4] 2> at
org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:96)
[junit4] 2> at
org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:52)
[junit4] 2> at
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:50)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:771)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:424)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:202)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:137)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at
org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at
org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at
org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at
org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at
org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at
org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at
org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at
org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at
org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 123692 T232 oas.SolrTestCaseJ4.tearDown ###Ending
testChildDoctransformer
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=SolrExampleBinaryTest -Dtests.method=testChildDoctransformer
-Dtests.seed=C7D57CBE21150A8C -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=sk_SK -Dtests.timezone=America/Marigot
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.91s J1 | SolrExampleBinaryTest.testChildDoctransformer <<<
[junit4] > Throwable #1:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Expected
mime type application/octet-stream but got text/html. <html>
[junit4] > <head>
[junit4] > <meta http-equiv="Content-Type"
content="text/html;charset=ISO-8859-1"/>
[junit4] > <title>Error 500 Server Error</title>
[junit4] > </head>
[junit4] > <body>
[junit4] > <h2>HTTP ERROR: 500</h2>
[junit4] > <p>Problem accessing /solr/collection1/select. Reason:
[junit4] > <pre> Server Error</pre></p>
[junit4] > <hr /><i><small>Powered by Jetty://</small></i>
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] > </body>
[junit4] > </html>
[junit4] > at
__randomizedtesting.SeedInfo.seed([C7D57CBE21150A8C:B40F6324AD0D7D8A]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:530)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
[junit4] > at
org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
[junit4] > at
org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
[junit4] > at
org.apache.solr.client.solrj.SolrExampleTests.testChildDoctransformer(SolrExampleTests.java:1373)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 123785 T232 oas.SolrTestCaseJ4.setUp ###Starting
testMultiContentStreamRequest
[junit4] 2> 123788 T240 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-240]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 123788 T240 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-240]: nrtIsCurrent: infoVersion matches: true; DW changes:
true; BD changes: false
[junit4] 2> 123788 T240 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-240]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 123789 T240 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-240]: flush at getReader
[junit4] 2> 123789 T240 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-240]: startFullFlush
[junit4] 2> 123789 T240 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-240]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 123790 T240 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-240]: qtp32896926-240: flush naked frozen global deletes
[junit4] 2> 123790 T240 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-240]: push deletes 1 deleted queries bytesUsed=64 delGen=8
packetCount=1 totBytesUsed=64
[junit4] 2> 123791 T240 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-240]: flush: push buffered updates: 1 deleted queries
bytesUsed=64
[junit4] 2> 123791 T240 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-240]: apply all deletes during flush
[junit4] 2> 123791 T240 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-240]: applyDeletes: infos=[_0(5.0.0):C5766] packetCount=1
[junit4] 2> 123841 T240 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-240]: seg=_0(5.0.0):C5766 segGen=6 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=1,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=5766 100% deleted
[junit4] 2> 123841 T240 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-240]: applyDeletes took 50 msec
[junit4] 2> 123842 T240 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-240]: now checkpoint "_0(5.0.0):C5766/5766" [1 segments ;
isCommit = false]
[junit4] 2> 123842 T240 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-240]: 0 msec to checkpoint
[junit4] 2> 123842 T240 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-240]: drop 100% deleted segments: _0(5.0.0):C5766/5766
[junit4] 2> 123843 T240 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-240]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 123843 T240 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-240]: 0 msec to checkpoint
[junit4] 2> 123843 T240 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-240]: prune sis=segments_1: minGen=9223372036854775807
packetCount=1
[junit4] 2> 123843 T240 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-240]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 123844 T240 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-240]: return reader version=5
reader=StandardDirectoryReader(segments_1:5:nrt)
[junit4] 2> 123844 T240 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-240]: qtp32896926-240 finishFullFlush success=true
[junit4] 2> 123844 T240 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-240]: getReader took 55 msec
[junit4] 2> 123844 T240 C158 oass.SolrIndexSearcher.<init> Opening
Searcher@1c966b9[collection1] realtime
[junit4] 2> 123845 T240 C158 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*
(-1480739989802188800)} 0 58
[junit4] 2> 123849 T239 C158 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 123849 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: commit: start
[junit4] 2> 123849 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: commit: enter lock
[junit4] 2> 123850 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: commit: now prepare
[junit4] 2> 123850 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: prepareCommit: flush
[junit4] 2> 123850 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: index before flush
[junit4] 2> 123851 T239 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-239]: startFullFlush
[junit4] 2> 123851 T239 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-239]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 123851 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: apply all deletes during flush
[junit4] 2> 123852 T239 C158 oasu.LoggingInfoStream.message
[BD][qtp32896926-239]: prune sis=segments_1: minGen=9223372036854775807
packetCount=0
[junit4] 2> 123852 T239 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-239]: qtp32896926-239 finishFullFlush success=true
[junit4] 2> 123852 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: startCommit(): start
[junit4] 2> 123853 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: startCommit index= changeCount=6
[junit4] 2> 123853 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: done all syncs: []
[junit4] 2> 123853 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: commit: pendingCommit != null
[junit4] 2> 123854 T239 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-239]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 123854 T239 C158 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-b5060f)),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-b5060f)),segFN=segments_3,generation=3}
[junit4] 2> 123855 T239 C158 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 3
[junit4] 2> 123855 T239 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-239]: deleteCommits: now decRef commit "segments_2"
[junit4] 2> 123855 T239 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-239]: delete "segments_2"
[junit4] 2> 123856 T239 C158 oasu.LoggingInfoStream.message
[IFD][qtp32896926-239]: 1 msec to checkpoint
[junit4] 2> 123856 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: commit: wrote segments file "segments_3"
[junit4] 2> 123856 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: commit: took 6.3 msec
[junit4] 2> 123857 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: commit: done
[junit4] 2> 123857 T239 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-239]: anyChanges? numDocsInRam=0 deletes=false
hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 123857 T239 C158 oasu.LoggingInfoStream.message
[IW][qtp32896926-239]: nrtIsCurrent: infoVersion matches: true; DW changes:
false; BD changes: false
[junit4] 2> 123858 T239 C158 oasu.LoggingInfoStream.message
[DW][qtp32896926-239]: anyChanges
[...truncated too long message...]
0
[junit4] 2> 227283 T516 C210 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: startFullFlush
[junit4] 2> 227283 T516 C210 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: anyChanges?
numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 227284 T516 C210 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]:
SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker finishFullFlush
success=true
[junit4] 2> 227284 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: apply all
deletes during flush
[junit4] 2> 227284 T516 C210 oasu.LoggingInfoStream.message
[BD][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: applyDeletes: no
deletes; skipping
[junit4] 2> 227284 T516 C210 oasu.LoggingInfoStream.message
[BD][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: prune
sis=segments_1: _1e(5.0.0):C10 minGen=298 packetCount=0
[junit4] 2> 227285 T516 C210 oasu.LoggingInfoStream.message
[CMS][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: now merge
[junit4] 2> 227285 T516 C210 oasu.LoggingInfoStream.message
[CMS][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: index:
_1e(5.0.0):C10
[junit4] 2> 227285 T516 C210 oasu.LoggingInfoStream.message
[CMS][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: no more
merges pending; now return
[junit4] 2> 227285 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: waitForMerges
[junit4] 2> 227285 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: waitForMerges
done
[junit4] 2> 227286 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: commit: start
[junit4] 2> 227286 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: commit: enter
lock
[junit4] 2> 227286 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: commit: now
prepare
[junit4] 2> 227286 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: prepareCommit:
flush
[junit4] 2> 227287 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: index before
flush _1e(5.0.0):C10
[junit4] 2> 227287 T516 C210 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: startFullFlush
[junit4] 2> 227287 T516 C210 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: anyChanges?
numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 227287 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: apply all
deletes during flush
[junit4] 2> 227288 T516 C210 oasu.LoggingInfoStream.message
[BD][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: applyDeletes: no
deletes; skipping
[junit4] 2> 227288 T516 C210 oasu.LoggingInfoStream.message
[BD][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: prune
sis=segments_1: _1e(5.0.0):C10 minGen=298 packetCount=0
[junit4] 2> 227288 T516 C210 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]:
SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker finishFullFlush
success=true
[junit4] 2> 227288 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: startCommit():
start
[junit4] 2> 227289 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: skip
startCommit(): no changes pending
[junit4] 2> 227289 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: commit:
pendingCommit == null; skip
[junit4] 2> 227289 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: commit: wrote
segments file "null"
[junit4] 2> 227290 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: commit: took 3.1
msec
[junit4] 2> 227290 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: commit: done
[junit4] 2> 227290 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: rollback
[junit4] 2> 227290 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: all running
merges have aborted
[junit4] 2> 227291 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: rollback: done
finish merges
[junit4] 2> 227291 T516 C210 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: abort
[junit4] 2> 227291 T516 C210 oasu.LoggingInfoStream.message
[DW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: done abort;
abortedFiles=[] success=true
[junit4] 2> 227291 T516 C210 oasu.LoggingInfoStream.message
[IW][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: rollback:
infos=_1e(5.0.0):C10
[junit4] 2> 227292 T516 C210 oasu.LoggingInfoStream.message
[IFD][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: now checkpoint
"_1e(5.0.0):C10" [1 segments ; isCommit = false]
[junit4] 2> 227292 T516 C210 oasu.LoggingInfoStream.message
[IFD][SUITE-SolrExampleXMLTest-seed#[C7D57CBE21150A8C]-worker]: 0 msec to
checkpoint
[junit4] 2> 227293 T516 oasc.SolrCore.closeSearcher [collection1] Closing
main searcher on request.
[junit4] 2> 227293 T516 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 227293 T516 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest-C7D57CBE21150A8C-001/tempDir-001
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest-C7D57CBE21150A8C-001/tempDir-001;done=false>>]
[junit4] 2> 227294 T516 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest-C7D57CBE21150A8C-001/tempDir-001
[junit4] 2> 227294 T516 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest-C7D57CBE21150A8C-001/tempDir-001/index
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest-C7D57CBE21150A8C-001/tempDir-001/index;done=false>>]
[junit4] 2> 227294 T516 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest-C7D57CBE21150A8C-001/tempDir-001/index
[junit4] 2> 227295 T516 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 232248 T516 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest-C7D57CBE21150A8C-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {},
docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {},
locale=sr, timezone=Africa/Lome
[junit4] 2> NOTE: Linux 3.13.0-36-generic i386/Oracle Corporation
1.8.0_40-ea (32-bit)/cpus=8,threads=1,free=81188536,total=150994944
[junit4] 2> NOTE: All tests run in this JVM: [BasicHttpSolrServerTest,
NamedListTest, TestLBHttpSolrServer, QueryResponseTest,
MultiCoreExampleJettyTest, SolrDocumentTest, TestNamedListCodec,
TestJsonRecordReader, TestFastInputStream, SolrExampleBinaryTest,
TestSolrProperties, MultiCoreEmbeddedTest, TestSpellCheckResponse,
CloudSolrServerTest, SolrExampleStreamingTest, SolrExampleXMLTest]
[junit4] Completed on J1 in 12.03s, 28 tests, 1 error <<< FAILURES!
[...truncated 98 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:524: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:472: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:202: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:496: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1360:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:962:
There were test failures: 53 suites, 314 tests, 2 errors
Total time: 104 minutes 31 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_40-ea-b04 -server
-XX:+UseG1GC
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]
