Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11361/
Java: 64bit/jdk1.7.0_67 -XX:+UseCompressedOops -XX:+UseParallelGC

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([7F89A5284A18FDF8:C53BAB2C6008AFE]: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:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        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([7F89A5284A18FDF8:C53BAB2C6008AFE]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:570)
        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:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        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 12413 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.SolrExampleXMLTest
   [junit4]   2> Creating dataDir: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-7F89A5284A18FDF8-001/init-core-data-001
   [junit4]   2> 14935 T47 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> 14935 T47 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> 15068 T47 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 15169 T47 oejs.AbstractConnector.doStart Started 
[email protected]:56564
   [junit4]   2> 15185 T47 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 15185 T47 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 15185 T47 oasc.SolrResourceLoader.locateSolrHome using system 
property solr.solr.home: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
   [junit4]   2> 15185 T47 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/'
   [junit4]   2> 15197 T47 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/solr.xml
   [junit4]   2> 15222 T47 oasc.CorePropertiesLocator.<init> Config-defined 
core root directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
   [junit4]   2> 15223 T47 oasc.CoreContainer.<init> New CoreContainer 
1226837099
   [junit4]   2> 15223 T47 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/]
   [junit4]   2> 15232 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
socketTimeout to: 0
   [junit4]   2> 15232 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
urlScheme to: null
   [junit4]   2> 15232 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
connTimeout to: 0
   [junit4]   2> 15233 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
maxConnectionsPerHost to: 20
   [junit4]   2> 15233 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
corePoolSize to: 0
   [junit4]   2> 15233 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
maximumPoolSize to: 2147483647
   [junit4]   2> 15233 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
maxThreadIdleTime to: 5
   [junit4]   2> 15233 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
sizeOfQueue to: -1
   [junit4]   2> 15234 T47 oashc.HttpShardHandlerFactory.getParameter Setting 
fairnessPolicy to: false
   [junit4]   2> 15234 T47 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=0&connTimeout=0&retry=false
   [junit4]   2> 15234 T47 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 15235 T47 oasl.LogWatcher.newRegisteredLogWatcher Registering 
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 15235 T47 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 15237 T47 oasc.CorePropertiesLocator.discover Looking for core 
definitions underneath 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
   [junit4]   2> 15239 T47 oasc.CorePropertiesLocator.discoverUnder Found core 
collection1 in 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/
   [junit4]   2> 15239 T47 oasc.CorePropertiesLocator.discover Found 1 core 
definitions
   [junit4]   2> 15240 T59 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/'
   [junit4]   2> 15298 T59 oasc.SolrConfig.initLibs Adding specified lib dirs 
to ClassLoader
   [junit4]   2> 15298 T59 oasc.SolrResourceLoader.addToClassLoader WARN No 
files added to classloader from lib: ../../../contrib/extraction/lib (resolved 
as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
   [junit4]   2> 15298 T59 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../dist/ (resolved as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
   [junit4]   2> 15299 T59 oasc.SolrResourceLoader.addToClassLoader WARN No 
files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved 
as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
   [junit4]   2> 15299 T59 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../dist/ (resolved as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
   [junit4]   2> 15299 T59 oasc.SolrResourceLoader.addToClassLoader WARN No 
files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
   [junit4]   2> 15299 T59 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../dist/ (resolved as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
   [junit4]   2> 15300 T59 oasc.SolrResourceLoader.addToClassLoader WARN No 
files added to classloader from lib: ../../../contrib/velocity/lib (resolved 
as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
   [junit4]   2> 15300 T59 oasc.SolrResourceLoader.addToClassLoader WARN Can't 
find (or read) directory to add to classloader: ../../../dist/ (resolved as: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
   [junit4]   2> 15324 T59 oasu.SolrIndexConfig.<init> IndexWriter infoStream 
solr logging is enabled
   [junit4]   2> 15326 T59 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
6.0.0
   [junit4]   2> 15377 T59 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 15377 T59 oass.IndexSchema.readSchema Reading Solr Schema from 
schema.xml
   [junit4]   2> 15425 T59 oass.IndexSchema.readSchema [collection1] Schema 
name=example
   [junit4]   2> 15737 T59 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 15745 T59 oasr.RestManager$Registry.registerManagedResource 
Registered ManagedResource impl 
org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path 
/schema/analysis/stopwords/english
   [junit4]   2> 15745 T59 oasr.RestManager$Registry.registerManagedResource 
Registered ManagedResource impl 
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager 
for path /schema/analysis/synonyms/english
   [junit4]   2> 15767 T59 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 15773 T59 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 15855 T59 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/
   [junit4]   2> 15855 T59 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 15856 T59 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/,
 
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-7F89A5284A18FDF8-001/tempDir-001/
   [junit4]   2> 15856 T59 oasc.JmxMonitoredMap.<init> No JMX servers found, 
not exposing Solr information with JMX.
   [junit4]   2> 15856 T59 oasc.SolrCore.initListeners [collection1] Added 
SolrEventListener for newSearcher: 
org.apache.solr.core.QuerySenderListener{queries=[]}
   [junit4]   2> 15857 T59 oasc.SolrCore.initListeners [collection1] Added 
SolrEventListener for firstSearcher: 
org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher 
warming in solrconfig.xml}]}
   [junit4]   2> 15857 T59 oasc.CachingDirectoryFactory.get return new 
directory for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-7F89A5284A18FDF8-001/tempDir-001
   [junit4]   2> 15857 T59 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-7F89A5284A18FDF8-001/tempDir-001/index/
   [junit4]   2> 15857 T59 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-7F89A5284A18FDF8-001/tempDir-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 15858 T59 oasc.CachingDirectoryFactory.get return new 
directory for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.SolrExampleXMLTest-7F89A5284A18FDF8-001/tempDir-001/index
   [junit4]   2> 15858 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: init: current segments file is "null"; 
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@224ce134
   [junit4]   2> 15859 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 15859 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: 0 msec to checkpoint
   [junit4]   2> 15859 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: init: create=true
   [junit4]   2> 15859 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: 
   [junit4]   2>        
dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd))
   [junit4]   2>        index=
   [junit4]   2>        version=6.0.0
   [junit4]   2>        analyzer=null
   [junit4]   2>        ramBufferSizeMB=100.0
   [junit4]   2>        maxBufferedDocs=-1
   [junit4]   2>        maxBufferedDeleteTerms=-1
   [junit4]   2>        mergedSegmentWarmer=null
   [junit4]   2>        
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
   [junit4]   2>        commit=null
   [junit4]   2>        openMode=CREATE
   [junit4]   2>        
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
   [junit4]   2>        mergeScheduler=ConcurrentMergeScheduler: 
maxThreadCount=1, maxMergeCount=2, mergeThreadPriority=-1
   [junit4]   2>        default WRITE_LOCK_TIMEOUT=1000
   [junit4]   2>        writeLockTimeout=1000
   [junit4]   2>        codec=Lucene50
   [junit4]   2>        infoStream=org.apache.solr.update.LoggingInfoStream
   [junit4]   2>        mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2>        
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@65552e83
   [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@3b60ce6e
   [junit4]   2>        
   [junit4]   2> 15860 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: now flush at close
   [junit4]   2> 15860 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]:   start flush: applyAllDeletes=true
   [junit4]   2> 15860 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]:   index before flush 
   [junit4]   2> 15860 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: startFullFlush
   [junit4]   2> 15860 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 15861 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: coreLoadExecutor-49-thread-1 
finishFullFlush success=true
   [junit4]   2> 15861 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: apply all deletes during flush
   [junit4]   2> 15861 T59 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-49-thread-1]: prune sis=null:  minGen=9223372036854775807 
packetCount=0
   [junit4]   2> 15861 T59 oasu.LoggingInfoStream.message 
[CMS][coreLoadExecutor-49-thread-1]: now merge
   [junit4]   2> 15861 T59 oasu.LoggingInfoStream.message 
[CMS][coreLoadExecutor-49-thread-1]:   index: 
   [junit4]   2> 15862 T59 oasu.LoggingInfoStream.message 
[CMS][coreLoadExecutor-49-thread-1]:   no more merges pending; now return
   [junit4]   2> 15862 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: waitForMerges
   [junit4]   2> 15862 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: waitForMerges done
   [junit4]   2> 15862 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: commit: start
   [junit4]   2> 15863 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: commit: enter lock
   [junit4]   2> 15863 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: commit: now prepare
   [junit4]   2> 15863 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: prepareCommit: flush
   [junit4]   2> 15863 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]:   index before flush 
   [junit4]   2> 15863 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: startFullFlush
   [junit4]   2> 15864 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 15864 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: apply all deletes during flush
   [junit4]   2> 15864 T59 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-49-thread-1]: prune sis=null:  minGen=9223372036854775807 
packetCount=0
   [junit4]   2> 15864 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: coreLoadExecutor-49-thread-1 
finishFullFlush success=true
   [junit4]   2> 15864 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: startCommit(): start
   [junit4]   2> 15865 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: startCommit index= changeCount=1
   [junit4]   2> 15865 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: done all syncs: []
   [junit4]   2> 15865 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: commit: pendingCommit != null
   [junit4]   2> 15865 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: now checkpoint "" [0 segments ; isCommit = 
true]
   [junit4]   2> 15866 T59 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd)),segFN=segments_1,generation=1}
   [junit4]   2> 15866 T59 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 15866 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: 0 msec to checkpoint
   [junit4]   2> 15866 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: commit: wrote segments file "segments_1"
   [junit4]   2> 15867 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: commit: took 3.5 msec
   [junit4]   2> 15867 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: commit: done
   [junit4]   2> 15867 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: rollback
   [junit4]   2> 15867 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: all running merges have aborted
   [junit4]   2> 15867 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: rollback: done finish merges
   [junit4]   2> 15868 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: abort
   [junit4]   2> 15868 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: done abort; abortedFiles=[] success=true
   [junit4]   2> 15868 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: rollback: infos=
   [junit4]   2> 15868 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 15868 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: 0 msec to checkpoint
   [junit4]   2> 15869 T59 oasc.SolrCore.initWriters created json: 
solr.JSONResponseWriter
   [junit4]   2> 15869 T59 oasc.SolrCore.initWriters adding lazy 
queryResponseWriter: solr.VelocityResponseWriter
   [junit4]   2> 15869 T59 oasc.SolrCore.initWriters created velocity: 
solr.VelocityResponseWriter
   [junit4]   2> 15869 T59 oasc.SolrCore.initWriters created xslt: 
solr.XSLTResponseWriter
   [junit4]   2> 15869 T59 oasr.XSLTResponseWriter.init 
xsltCacheLifetimeSeconds=5
   [junit4]   2> 15871 T59 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 15871 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/update: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 15871 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 15871 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 15872 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 15872 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/select: solr.SearchHandler
   [junit4]   2> 15872 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/query: solr.SearchHandler
   [junit4]   2> 15872 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/get: solr.RealTimeGetHandler
   [junit4]   2> 15873 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/export: solr.SearchHandler
   [junit4]   2> 15873 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/browse: solr.SearchHandler
   [junit4]   2> 15873 T59 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.extraction.ExtractingRequestHandler
   [junit4]   2> 15873 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/update/extract: solr.extraction.ExtractingRequestHandler
   [junit4]   2> 15874 T59 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.FieldAnalysisRequestHandler
   [junit4]   2> 15874 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/analysis/field: solr.FieldAnalysisRequestHandler
   [junit4]   2> 15874 T59 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.DocumentAnalysisRequestHandler
   [junit4]   2> 15874 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/analysis/document: solr.DocumentAnalysisRequestHandler
   [junit4]   2> 15874 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/: solr.admin.AdminHandlers
   [junit4]   2> 15875 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/admin/ping: solr.PingRequestHandler
   [junit4]   2> 15875 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/debug/dump: solr.DumpRequestHandler
   [junit4]   2> 15875 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/replication: solr.ReplicationHandler
   [junit4]   2> 15875 T59 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.SearchHandler
   [junit4]   2> 15876 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/spell: solr.SearchHandler
   [junit4]   2> 15876 T59 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.SearchHandler
   [junit4]   2> 15876 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/suggest: solr.SearchHandler
   [junit4]   2> 15876 T59 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.SearchHandler
   [junit4]   2> 15876 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/tvrh: solr.SearchHandler
   [junit4]   2> 15876 T59 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.SearchHandler
   [junit4]   2> 15877 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/terms: solr.SearchHandler
   [junit4]   2> 15877 T59 oasc.RequestHandlers.initHandlersFromConfig adding 
lazy requestHandler: solr.SearchHandler
   [junit4]   2> 15877 T59 oasc.RequestHandlers.initHandlersFromConfig created 
/elevate: solr.SearchHandler
   [junit4]   2> 15879 T59 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 15880 T59 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 15881 T59 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 15882 T59 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 15886 T59 oasu.CommitTracker.<init> Hard AutoCommit: if 
uncommited for 15000ms; 
   [junit4]   2> 15887 T59 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 15888 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: init: current segments file is 
"segments_1"; 
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@224ce134
   [junit4]   2> 15888 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: init: load commit "segments_1"
   [junit4]   2> 15888 T59 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd)),segFN=segments_1,generation=1}
   [junit4]   2> 15888 T59 oasc.SolrDeletionPolicy.updateCommits newest commit 
generation = 1
   [junit4]   2> 15889 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: now checkpoint "" [0 segments ; isCommit = 
false]
   [junit4]   2> 15889 T59 oasu.LoggingInfoStream.message 
[IFD][coreLoadExecutor-49-thread-1]: 0 msec to checkpoint
   [junit4]   2> 15889 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: init: create=false
   [junit4]   2> 15889 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: 
   [junit4]   2>        
dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd))
   [junit4]   2>        index=
   [junit4]   2>        version=6.0.0
   [junit4]   2>        analyzer=null
   [junit4]   2>        ramBufferSizeMB=100.0
   [junit4]   2>        maxBufferedDocs=-1
   [junit4]   2>        maxBufferedDeleteTerms=-1
   [junit4]   2>        mergedSegmentWarmer=null
   [junit4]   2>        
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
   [junit4]   2>        commit=null
   [junit4]   2>        openMode=APPEND
   [junit4]   2>        
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
   [junit4]   2>        mergeScheduler=ConcurrentMergeScheduler: 
maxThreadCount=1, maxMergeCount=2, mergeThreadPriority=-1
   [junit4]   2>        default WRITE_LOCK_TIMEOUT=1000
   [junit4]   2>        writeLockTimeout=1000
   [junit4]   2>        codec=Lucene50
   [junit4]   2>        infoStream=org.apache.solr.update.LoggingInfoStream
   [junit4]   2>        mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, 
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, 
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2>        
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@1d25b88f
   [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@1777c33c
   [junit4]   2>        
   [junit4]   2> 15889 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: flush at getReader
   [junit4]   2> 15890 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: startFullFlush
   [junit4]   2> 15890 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 15890 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: apply all deletes during flush
   [junit4]   2> 15890 T59 oasu.LoggingInfoStream.message 
[BD][coreLoadExecutor-49-thread-1]: prune sis=segments_1:  
minGen=9223372036854775807 packetCount=0
   [junit4]   2> 15890 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: return reader version=1 
reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 15891 T59 oasu.LoggingInfoStream.message 
[DW][coreLoadExecutor-49-thread-1]: coreLoadExecutor-49-thread-1 
finishFullFlush success=true
   [junit4]   2> 15891 T59 oasu.LoggingInfoStream.message 
[IW][coreLoadExecutor-49-thread-1]: getReader took 2 msec
   [junit4]   2> 15891 T59 oass.SolrIndexSearcher.<init> Opening 
Searcher@3138a2ec[collection1] main
   [junit4]   2> 15891 T59 oasr.ManagedResourceStorage.newStorageIO WARN Cannot 
write to config directory 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 15892 T59 oasr.RestManager.init Initializing RestManager with 
initArgs: {}
   [junit4]   2> 15892 T59 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 15892 T59 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 15892 T59 oasr.ManagedResourceStorage$JsonStorage.store Saved 
JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 15892 T59 oasr.RestManager.init Initializing 2 registered 
ManagedResources
   [junit4]   2> 15893 T59 oasr.ManagedResourceStorage.load Reading 
_schema_analysis_stopwords_english.json using InMemoryStorage
   [junit4]   2> 15893 T59 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /schema/analysis/stopwords/english
   [junit4]   2> 15893 T59 oasr.ManagedResourceStorage$JsonStorage.store Saved 
JSON object to path _schema_analysis_stopwords_english.json using 
InMemoryStorage
   [junit4]   2> 15893 T59 
oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 0 words for 
/schema/analysis/stopwords/english
   [junit4]   2> 15893 T59 oasr.ManagedResource.notifyObserversDuringInit 
Notified 1 observers of /schema/analysis/stopwords/english
   [junit4]   2> 15893 T59 oasr.ManagedResourceStorage.load Reading 
_schema_analysis_synonyms_english.json using InMemoryStorage
   [junit4]   2> 15894 T59 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /schema/analysis/synonyms/english
   [junit4]   2> 15894 T59 
oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage
 Loaded 0 synonym mappings for /schema/analysis/synonyms/english
   [junit4]   2> 15894 T59 oasr.ManagedResource.notifyObserversDuringInit 
Notified 1 observers of /schema/analysis/synonyms/english
   [junit4]   2> 15894 T59 oashc.SpellCheckComponent.inform Initializing spell 
checkers
   [junit4]   2> 15894 T59 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> 15895 T59 oashc.SpellCheckComponent.inform No queryConverter 
defined, using default converter
   [junit4]   2> 15895 T59 oashc.SuggestComponent.inform Initializing 
SuggestComponent
   [junit4]   2> 15895 T59 oasss.SolrSuggester.init init: 
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
   [junit4]   2> 15896 T59 oasss.SolrSuggester.init Dictionary loaded with 
params: 
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string}
   [junit4]   2> 15896 T59 oashc.QueryElevationComponent.inform Loading 
QueryElevation from: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/conf/elevate.xml
   [junit4]   2> 15903 T59 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 15904 T60 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@3138a2ec[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 15904 T59 oasc.CoreContainer.registerCore registering core: 
collection1
   [junit4]   2> 15905 T47 oass.SolrDispatchFilter.init 
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J0
   [junit4]   2> 15905 T47 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 15905 T47 oas.SolrJettyTestBase.createJetty Jetty Assigned 
Port#56564
   [junit4]   2> ASYNC  NEW_CORE C3 name=collection1 
org.apache.solr.core.SolrCore@7e1ce520
   [junit4]   2> 15906 T60 C3 oasc.SolrCore.execute [collection1] webapp=null 
path=null 
params={distrib=false&q=static+firstSearcher+warming+in+solrconfig.xml&event=firstSearcher}
 hits=0 status=0 QTime=1 
   [junit4]   2> 15906 T60 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 15906 T60 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: default
   [junit4]   2> 15906 T60 
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index 
for spellchecker: wordbreak
   [junit4]   2> 15906 T60 oashc.SuggestComponent$SuggesterListener.newSearcher 
Loading suggester index for: mySuggester
   [junit4]   2> 15907 T60 oasss.SolrSuggester.reload reload()
   [junit4]   2> 15907 T60 oasss.SolrSuggester.build build()
   [junit4]   2> 15909 T60 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3138a2ec[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 15913 T47 oas.SolrTestCaseJ4.setUp ###Starting 
testChildDoctransformer
   [junit4]   2> 16032 T56 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-56]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 16033 T56 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-56]: nrtIsCurrent: infoVersion matches: true; DW changes: 
true; BD changes: false
   [junit4]   2> 16034 T56 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-56]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 16035 T56 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-56]: flush at getReader
   [junit4]   2> 16035 T56 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-56]: startFullFlush
   [junit4]   2> 16036 T56 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-56]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 16037 T56 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-56]: qtp521133693-56: flush naked frozen global deletes
   [junit4]   2> 16038 T56 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-56]: push deletes  1 deleted queries bytesUsed=64 delGen=2 
packetCount=1 totBytesUsed=64
   [junit4]   2> 16039 T56 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-56]: flush: push buffered updates:  1 deleted queries 
bytesUsed=64
   [junit4]   2> 16039 T56 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-56]: apply all deletes during flush
   [junit4]   2> 16040 T56 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-56]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=1
   [junit4]   2> 16041 T56 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-56]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 16042 T56 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-56]: return reader version=1 
reader=StandardDirectoryReader(segments_1:1:nrt)
   [junit4]   2> 16042 T56 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-56]: qtp521133693-56 finishFullFlush success=true
   [junit4]   2> 16043 T56 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-56]: getReader took 8 msec
   [junit4]   2> 16044 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* 
(-1480746190904492032)} 0 16
   [junit4]   2> 16102 T57 C3 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 16102 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 16103 T57 C3 oasu.DirectUpdateHandler2.commit No uncommitted 
changes. Skipping IW.commit.
   [junit4]   2> 16103 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 16103 T57 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-57]: nrtIsCurrent: infoVersion matches: true; DW changes: 
false; BD changes: false
   [junit4]   2> 16104 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 16104 T57 C3 oasc.SolrCore.openNewSearcher SolrIndexSearcher 
has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 16105 T57 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 16105 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update 
params={wt=xml&softCommit=false&version=2.2&waitSearcher=true&commit=true} 
{commit=} 0 3
   [junit4]   2> 16110 T58 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 
(1480746190986280960)]} 0 2
   [junit4]   2> 16114 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 
(1480746190992572416)]} 0 1
   [junit4]   2> 16164 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 
(1480746191014592512)]} 0 43
   [junit4]   2> 16239 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[217 
(1480746191083798528)]} 0 61
   [junit4]   2> 16244 T58 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[519 
(1480746191127838720)]} 0 1
   [junit4]   2> 16248 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[520 
(1480746191133081600)]} 0 1
   [junit4]   2> 16400 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[522 
(1480746191203336192)]} 0 119
   [junit4]   2> 16626 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1227 
(1480746191362719744)]} 0 190
   [junit4]   2> 16723 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2227 
(1480746191555657728)]} 0 86
   [junit4]   2> 16729 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2505 
(1480746191637446656)]} 0 1
   [junit4]   2> 16733 T58 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2506 
(1480746191641640960)]} 0 0
   [junit4]   2> 16738 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2507 
(1480746191645835264)]} 0 1
   [junit4]   2> 16742 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2508 
(1480746191650029568)]} 0 1
   [junit4]   2> 16746 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2509 
(1480746191655272448)]} 0 1
   [junit4]   2> 16751 T58 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2510 
(1480746191659466752)]} 0 1
   [junit4]   2> 16755 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2511 
(1480746191664709632)]} 0 0
   [junit4]   2> 16759 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2512 
(1480746191668903936)]} 0 0
   [junit4]   2> 16915 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2513 
(1480746191706652672)]} 0 135
   [junit4]   2> 16919 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[3195 
(1480746191836676096)]} 0 0
   [junit4]   2> 16979 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[3196 
(1480746191866036224)]} 0 42
   [junit4]   2> 17009 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[3670 
(1480746191913222144)]} 0 23
   [junit4]   2> 17038 T58 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[3992 
(1480746191943630848)]} 0 23
   [junit4]   2> 17043 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[4203 
(1480746191966699520)]} 0 1
   [junit4]   2> 17085 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[4204 
(1480746191985573888)]} 0 33
   [junit4]   2> 17089 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[4737 
(1480746192014934016)]} 0 0
   [junit4]   2> 17093 T58 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[4738 
(1480746192019128320)]} 0 0
   [junit4]   2> 17128 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[4739 
(1480746192038002688)]} 0 27
   [junit4]   2> 17160 T56 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[5208 
(1480746192072605696)]} 0 24
   [junit4]   2> 17164 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[5716 
(1480746192093577216)]} 0 1
   [junit4]   2> 17168 T58 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[5717 
(1480746192096722944)]} 0 1
   [junit4]   2> 17171 T55 C3 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 17172 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: start
   [junit4]   2> 17172 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: enter lock
   [junit4]   2> 17172 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: now prepare
   [junit4]   2> 17173 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: prepareCommit: flush
   [junit4]   2> 17173 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]:   index before flush 
   [junit4]   2> 17173 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: startFullFlush
   [junit4]   2> 17173 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: anyChanges? numDocsInRam=5718 deletes=true 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 17174 T55 C3 oasu.LoggingInfoStream.message 
[DWFC][qtp521133693-55]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0 29 deleted terms (unique count=29) bytesUsed=4966, 
segment=_0, aborting=false, numDocsInRAM=5718, deleteQueue=DWDQ: [ generation: 
2 ]]
   [junit4]   2> 17174 T55 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-55]: flush postings as segment _0 numDocs=5718
   [junit4]   2> 17358 T55 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-55]: new segment has 0 deleted docs
   [junit4]   2> 17358 T55 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-55]: new segment has no vectors; norms; no docValues; prox; 
freqs
   [junit4]   2> 17358 T55 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-55]: flushedFiles=[_0.nvd, _0_Lucene41_0.tip, _0.fnm, 
_0_Lucene41_0.pos, _0_Lucene41_0.tim, _0_Lucene41_0.doc, _0.fdx, _0.fdt, _0.nvm]
   [junit4]   2> 17359 T55 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-55]: flushed codec=Lucene50
   [junit4]   2> 17359 T55 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-55]: flushed: segment=_0 ramUsed=0.33 MB 
newFlushedSize(includes docstores)=0.1 MB docs/MB=56,937.064
   [junit4]   2> 17360 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 17360 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: publishFlushedSegment
   [junit4]   2> 17360 T55 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-55]: push deletes  30 deleted terms (unique count=30) 
bytesUsed=1056 delGen=4 packetCount=1 totBytesUsed=1056
   [junit4]   2> 17361 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: publish sets newSegment delGen=5 seg=_0(6.0.0):C5718
   [junit4]   2> 17361 T55 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-55]: now checkpoint "_0(6.0.0):C5718" [1 segments ; isCommit 
= false]
   [junit4]   2> 17361 T55 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-55]: 0 msec to checkpoint
   [junit4]   2> 17362 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: apply all deletes during flush
   [junit4]   2> 17362 T55 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-55]: applyDeletes: infos=[_0(6.0.0):C5718] packetCount=1
   [junit4]   2> 17362 T55 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-55]: applyDeletes took 0 msec
   [junit4]   2> 17363 T55 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-55]: prune sis=segments_1: _0(6.0.0):C5718 minGen=6 
packetCount=1
   [junit4]   2> 17363 T55 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-55]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 17364 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: qtp521133693-55 finishFullFlush success=true
   [junit4]   2> 17364 T55 C3 oasu.LoggingInfoStream.message 
[TMP][qtp521133693-55]: findMerges: 1 segments
   [junit4]   2> 17364 T55 C3 oasu.LoggingInfoStream.message 
[TMP][qtp521133693-55]:   seg=_0(6.0.0):C5718 size=0.101 MB [floored]
   [junit4]   2> 17365 T55 C3 oasu.LoggingInfoStream.message 
[TMP][qtp521133693-55]:   allowedSegmentCount=1 vs count=1 (eligible count=1) 
tooBigCount=0
   [junit4]   2> 17365 T55 C3 oasu.LoggingInfoStream.message 
[CMS][qtp521133693-55]: now merge
   [junit4]   2> 17365 T55 C3 oasu.LoggingInfoStream.message 
[CMS][qtp521133693-55]:   index: _0(6.0.0):C5718
   [junit4]   2> 17366 T55 C3 oasu.LoggingInfoStream.message 
[CMS][qtp521133693-55]:   no more merges pending; now return
   [junit4]   2> 17366 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: startCommit(): start
   [junit4]   2> 17366 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: startCommit index=_0(6.0.0):C5718 changeCount=3
   [junit4]   2> 17367 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: done all syncs: [_0_Lucene41_0.tip, _0.nvd, _0.fnm, 
_0_Lucene41_0.tim, _0_Lucene41_0.pos, _0_Lucene41_0.doc, _0.fdx, _0.si, _0.nvm, 
_0.fdt]
   [junit4]   2> 17368 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: pendingCommit != null
   [junit4]   2> 17368 T55 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-55]: now checkpoint "_0(6.0.0):C5718" [1 segments ; isCommit 
= true]
   [junit4]   2> 17368 T55 C3 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd)),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd)),segFN=segments_2,generation=2}
   [junit4]   2> 17369 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 17369 T55 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-55]: deleteCommits: now decRef commit "segments_1"
   [junit4]   2> 17370 T55 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-55]: delete "segments_1"
   [junit4]   2> 17370 T55 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-55]: 2 msec to checkpoint
   [junit4]   2> 17370 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: wrote segments file "segments_2"
   [junit4]   2> 17371 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: took 198.0 msec
   [junit4]   2> 17371 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: done
   [junit4]   2> 17371 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 17372 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 17372 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: flush at getReader
   [junit4]   2> 17372 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: startFullFlush
   [junit4]   2> 17373 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 17373 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: apply all deletes during flush
   [junit4]   2> 17374 T55 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-55]: applyDeletes: no deletes; skipping
   [junit4]   2> 17374 T55 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-55]: prune sis=segments_1: _0(6.0.0):C5718 minGen=6 
packetCount=0
   [junit4]   2> 17376 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: return reader version=3 
reader=StandardDirectoryReader(segments_1:3:nrt _0(6.0.0):C5718)
   [junit4]   2> 17377 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: qtp521133693-55 finishFullFlush success=true
   [junit4]   2> 17377 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: getReader took 5 msec
   [junit4]   2> 17377 T55 C3 oass.SolrIndexSearcher.<init> Opening 
Searcher@4627b1[collection1] main
   [junit4]   2> 17378 T55 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 17378 T60 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@4627b1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C5718)))}
   [junit4]   2> 17379 T60 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 17379 T60 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@4627b1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C5718)))}
   [junit4]   2> 17379 T55 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update 
params={wt=xml&softCommit=false&version=2.2&waitSearcher=true&commit=true} 
{commit=} 0 208
   [junit4]   2> 17385 T57 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select params={q=*:*&wt=xml&version=2.2} hits=5718 status=0 QTime=3 
   [junit4]   2> 17392 T58 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select 
params={q=level_i:0&wt=xml&fl=*,[child+parentFilter%3D"level_i:0"]&version=2.2} 
hits=30 status=0 QTime=1 
   [junit4]   2> 17413 T55 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select 
params={q=*:*&wt=xml&fl=id,[child+parentFilter%3D"level_i:1"+childFilter%3D"level_i:2"+limit%3D"31"]&fq=level_i:1&version=2.2}
 hits=58 status=0 QTime=2 
   [junit4]   2> 17428 T56 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select 
params={q=*:*&wt=xml&fl=id,[child+parentFilter%3D"level_i:0"+childFilter%3D"level_i:[0+TO+0]"+limit%3D"5"]&fq=level_i:0&version=2.2}
 hits=30 status=0 QTime=2 
   [junit4]   2> 17430 T56 C3 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:227)
   [junit4]   2>                at 
org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1634)
   [junit4]   2>                at 
org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1457)
   [junit4]   2>                at 
org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:538)
   [junit4]   2>                at 
org.apache.solr.search.SolrIndexSearcher.getDocList(SolrIndexSearcher.java:1292)
   [junit4]   2>                at 
org.apache.solr.response.transform.ChildDocTransformer.transform(ChildDocTransformerFactory.java:144)
   [junit4]   2>                at 
org.apache.solr.response.TextResponseWriter.writeDocuments(TextResponseWriter.java:254)
   [junit4]   2>                at 
org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:172)
   [junit4]   2>                at 
org.apache.solr.response.XMLWriter.writeResponse(XMLWriter.java:112)
   [junit4]   2>                at 
org.apache.solr.response.XMLResponseWriter.write(XMLResponseWriter.java:40)
   [junit4]   2>                at 
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:53)
   [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> 17433 T56 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:227)
   [junit4]   2>        at 
org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1634)
   [junit4]   2>        at 
org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1457)
   [junit4]   2>        at 
org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:538)
   [junit4]   2>        at 
org.apache.solr.search.SolrIndexSearcher.getDocList(SolrIndexSearcher.java:1292)
   [junit4]   2>        at 
org.apache.solr.response.transform.ChildDocTransformer.transform(ChildDocTransformerFactory.java:144)
   [junit4]   2>        at 
org.apache.solr.response.TextResponseWriter.writeDocuments(TextResponseWriter.java:254)
   [junit4]   2>        at 
org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:172)
   [junit4]   2>        at 
org.apache.solr.response.XMLWriter.writeResponse(XMLWriter.java:112)
   [junit4]   2>        at 
org.apache.solr.response.XMLResponseWriter.write(XMLResponseWriter.java:40)
   [junit4]   2>        at 
org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:53)
   [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> 17435 T47 oas.SolrTestCaseJ4.tearDown ###Ending 
testChildDoctransformer
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleXMLTest 
-Dtests.method=testChildDoctransformer -Dtests.seed=7F89A5284A18FDF8 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=tr_TR 
-Dtests.timezone=America/St_Vincent -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   1.58s J0 | SolrExampleXMLTest.testChildDoctransformer <<<
   [junit4]    > Throwable #1: 
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: 
java.lang.AssertionError
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([7F89A5284A18FDF8:C53BAB2C6008AFE]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:570)
   [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> 17490 T47 oas.SolrTestCaseJ4.setUp ###Starting 
testFieldGlobbing
   [junit4]   2> 17495 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testFieldGlobbing 
(1480746192438558720)]} 0 2
   [junit4]   2> 17498 T58 C3 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 17499 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: commit: start
   [junit4]   2> 17499 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: commit: enter lock
   [junit4]   2> 17499 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: commit: now prepare
   [junit4]   2> 17499 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: prepareCommit: flush
   [junit4]   2> 17500 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]:   index before flush _0(6.0.0):C5718
   [junit4]   2> 17500 T58 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-58]: startFullFlush
   [junit4]   2> 17500 T58 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-58]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 17500 T58 C3 oasu.LoggingInfoStream.message 
[DWFC][qtp521133693-58]: addFlushableState DocumentsWriterPerThread 
[pendingDeletes=gen=0, segment=_1, aborting=false, numDocsInRAM=1, 
deleteQueue=DWDQ: [ generation: 4 ]]
   [junit4]   2> 17501 T58 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-58]: flush postings as segment _1 numDocs=1
   [junit4]   2> 17502 T58 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-58]: new segment has 0 deleted docs
   [junit4]   2> 17503 T58 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-58]: new segment has no vectors; no norms; no docValues; no 
prox; no freqs
   [junit4]   2> 17503 T58 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-58]: flushedFiles=[_1_Lucene41_0.tim, _1_Lucene41_0.doc, 
_1.fdt, _1.fnm, _1.fdx, _1_Lucene41_0.tip]
   [junit4]   2> 17503 T58 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-58]: flushed codec=Lucene50
   [junit4]   2> 17504 T58 C3 oasu.LoggingInfoStream.message 
[DWPT][qtp521133693-58]: flushed: segment=_1 ramUsed=0.063 MB 
newFlushedSize(includes docstores)=0.001 MB docs/MB=860.9
   [junit4]   2> 17504 T58 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-58]: publishFlushedSegment seg-private updates=null
   [junit4]   2> 17504 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: publishFlushedSegment
   [junit4]   2> 17504 T58 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-58]: push deletes  1 deleted terms (unique count=1) 
bytesUsed=1056 delGen=8 packetCount=1 totBytesUsed=1056
   [junit4]   2> 17505 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: publish sets newSegment delGen=9 seg=_1(6.0.0):C1
   [junit4]   2> 17505 T58 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-58]: now checkpoint "_0(6.0.0):C5718 _1(6.0.0):C1" [2 
segments ; isCommit = false]
   [junit4]   2> 17505 T58 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-58]: 0 msec to checkpoint
   [junit4]   2> 17505 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: apply all deletes during flush
   [junit4]   2> 17506 T58 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-58]: applyDeletes: infos=[_0(6.0.0):C5718, _1(6.0.0):C1] 
packetCount=1
   [junit4]   2> 17506 T58 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-58]: seg=_0(6.0.0):C5718 segGen=6 coalesced 
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
 newDelCount=0
   [junit4]   2> 17506 T58 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-58]: applyDeletes took 0 msec
   [junit4]   2> 17506 T58 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-58]: prune sis=segments_1: _0(6.0.0):C5718 _1(6.0.0):C1 
minGen=10 packetCount=1
   [junit4]   2> 17507 T58 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-58]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 17507 T58 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-58]: qtp521133693-58 finishFullFlush success=true
   [junit4]   2> 17507 T58 C3 oasu.LoggingInfoStream.message 
[TMP][qtp521133693-58]: findMerges: 2 segments
   [junit4]   2> 17508 T58 C3 oasu.LoggingInfoStream.message 
[TMP][qtp521133693-58]:   seg=_0(6.0.0):C5718 size=0.101 MB [floored]
   [junit4]   2> 17509 T58 C3 oasu.LoggingInfoStream.message 
[TMP][qtp521133693-58]:   seg=_1(6.0.0):C1 size=0.001 MB [floored]
   [junit4]   2> 17509 T58 C3 oasu.LoggingInfoStream.message 
[TMP][qtp521133693-58]:   allowedSegmentCount=1 vs count=2 (eligible count=2) 
tooBigCount=0
   [junit4]   2> 17509 T58 C3 oasu.LoggingInfoStream.message 
[CMS][qtp521133693-58]: now merge
   [junit4]   2> 17510 T58 C3 oasu.LoggingInfoStream.message 
[CMS][qtp521133693-58]:   index: _0(6.0.0):C5718 _1(6.0.0):C1
   [junit4]   2> 17510 T58 C3 oasu.LoggingInfoStream.message 
[CMS][qtp521133693-58]:   no more merges pending; now return
   [junit4]   2> 17510 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: startCommit(): start
   [junit4]   2> 17510 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: startCommit index=_0(6.0.0):C5718 _1(6.0.0):C1 
changeCount=6
   [junit4]   2> 17561 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: done all syncs: [_0.nvd, _1_Lucene41_0.tim, _0.fnm, 
_1.si, _1_Lucene41_0.doc, _0_Lucene41_0.pos, _1.fdt, _0.fdx, _0.si, _0.fdt, 
_1.fnm, _1_Lucene41_0.tip, _0_Lucene41_0.tip, _0_Lucene41_0.tim, 
_0_Lucene41_0.doc, _0.nvm, _1.fdx]
   [junit4]   2> 17561 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: commit: pendingCommit != null
   [junit4]   2> 17562 T58 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-58]: now checkpoint "_0(6.0.0):C5718 _1(6.0.0):C1" [2 
segments ; isCommit = true]
   [junit4]   2> 17562 T58 C3 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd)),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd)),segFN=segments_3,generation=3}
   [junit4]   2> 17562 T58 C3 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 17563 T58 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-58]: deleteCommits: now decRef commit "segments_2"
   [junit4]   2> 17563 T58 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-58]: delete "segments_2"
   [junit4]   2> 17563 T58 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-58]: 1 msec to checkpoint
   [junit4]   2> 17563 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: commit: wrote segments file "segments_3"
   [junit4]   2> 17564 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: commit: took 64.3 msec
   [junit4]   2> 17564 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: commit: done
   [junit4]   2> 17564 T58 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-58]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 17564 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: nrtIsCurrent: infoVersion matches: false; DW changes: 
false; BD changes: false
   [junit4]   2> 17565 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: flush at getReader
   [junit4]   2> 17565 T58 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-58]: startFullFlush
   [junit4]   2> 17565 T58 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-58]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 17565 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: apply all deletes during flush
   [junit4]   2> 17566 T58 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-58]: applyDeletes: no deletes; skipping
   [junit4]   2> 17566 T58 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-58]: prune sis=segments_1: _0(6.0.0):C5718 _1(6.0.0):C1 
minGen=10 packetCount=0
   [junit4]   2> 17567 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: return reader version=5 
reader=StandardDirectoryReader(segments_1:5:nrt _0(6.0.0):C5718 _1(6.0.0):C1)
   [junit4]   2> 17567 T58 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-58]: qtp521133693-58 finishFullFlush success=true
   [junit4]   2> 17567 T58 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-58]: getReader took 2 msec
   [junit4]   2> 17568 T58 C3 oass.SolrIndexSearcher.<init> Opening 
Searcher@62e5aa2d[collection1] main
   [junit4]   2> 17569 T58 C3 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 17569 T60 oasc.QuerySenderListener.newSearcher 
QuerySenderListener sending requests to Searcher@62e5aa2d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C5718)
 Uninverting(_1(6.0.0):C1)))}
   [junit4]   2> 17569 T60 oasc.QuerySenderListener.newSearcher 
QuerySenderListener done.
   [junit4]   2> 17569 T60 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@62e5aa2d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C5718)
 Uninverting(_1(6.0.0):C1)))}
   [junit4]   2> 17570 T58 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update 
params={wt=xml&softCommit=false&version=2.2&waitSearcher=true&commit=true} 
{commit=} 0 72
   [junit4]   2> 17574 T56 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select params={q=id:testFieldGlobbing&wt=xml&fl=id,*_s&version=2.2} 
hits=1 status=0 QTime=1 
   [junit4]   2> 17578 T57 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select params={q=id:testFieldGlobbing&wt=xml&fl=*_s&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> 17581 T55 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select params={q=id:testFieldGlobbing&wt=xml&fl=id&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> 17585 T58 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select 
params={q=id:testFieldGlobbing&wt=xml&fl=id,[docid],*_s&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> 17589 T56 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select params={q=id:testFieldGlobbing&wt=xml&fl=[docid],*_s&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 17592 T57 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select params={q=id:testFieldGlobbing&wt=xml&fl=[docid]&version=2.2} 
hits=1 status=0 QTime=0 
   [junit4]   2> 17596 T55 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select params={q=id:testFieldGlobbing&wt=xml&fl=score&version=2.2} hits=1 
status=0 QTime=1 
   [junit4]   2> 17600 T58 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select 
params={q=id:testFieldGlobbing&wt=xml&fl=score,[docid]&version=2.2} hits=1 
status=0 QTime=0 
   [junit4]   2> 17604 T56 C3 oasc.SolrCore.execute [collection1] webapp=/solr 
path=/select params={q=id:testFieldGlobbing&wt=xml&fl=score,*_s&version=2.2} 
hits=1 status=0 QTime=1 
   [junit4]   2> 17605 T47 oas.SolrTestCaseJ4.tearDown ###Ending 
testFieldGlobbing
   [junit4]   2> 17613 T47 oas.SolrTestCaseJ4.setUp ###Starting testStatistics
   [junit4]   2> 17616 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 17616 T57 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-57]: nrtIsCurrent: infoVersion matches: true; DW changes: 
true; BD changes: false
   [junit4]   2> 17617 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 17617 T57 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-57]: flush at getReader
   [junit4]   2> 17617 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: startFullFlush
   [junit4]   2> 17617 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false 
pendingChangesInFullFlush: false
   [junit4]   2> 17618 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: qtp521133693-57: flush naked frozen global deletes
   [junit4]   2> 17618 T57 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-57]: push deletes  1 deleted queries bytesUsed=64 delGen=12 
packetCount=1 totBytesUsed=64
   [junit4]   2> 17618 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: flush: push buffered updates:  1 deleted queries 
bytesUsed=64
   [junit4]   2> 17618 T57 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-57]: apply all deletes during flush
   [junit4]   2> 17619 T57 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-57]: applyDeletes: infos=[_0(6.0.0):C5718, _1(6.0.0):C1] 
packetCount=1
   [junit4]   2> 17619 T57 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-57]: seg=_1(6.0.0):C1 segGen=10 coalesced 
deletes=[CoalescedUpdates(termSets=1,queries=1,numericDVUpdates=0,binaryDVUpdates=0)]
 newDelCount=1 100% deleted
   [junit4]   2> 17636 T57 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-57]: seg=_0(6.0.0):C5718 segGen=10 coalesced 
deletes=[CoalescedUpdates(termSets=1,queries=1,numericDVUpdates=0,binaryDVUpdates=0)]
 newDelCount=5718 100% deleted
   [junit4]   2> 17636 T57 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-57]: applyDeletes took 17 msec
   [junit4]   2> 17637 T57 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-57]: now checkpoint "_0(6.0.0):C5718/5718 _1(6.0.0):C1/1" [2 
segments ; isCommit = false]
   [junit4]   2> 17637 T57 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-57]: 0 msec to checkpoint
   [junit4]   2> 17637 T57 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-57]: drop 100% deleted segments: _1(6.0.0):C1/1 
_0(6.0.0):C5718/5718
   [junit4]   2> 17638 T57 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-57]: now checkpoint "" [0 segments ; isCommit = false]
   [junit4]   2> 17638 T57 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-57]: 0 msec to checkpoint
   [junit4]   2> 17638 T57 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-57]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=1
   [junit4]   2> 17638 T57 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-57]: pruneDeletes: prune 1 packets; 0 packets remain
   [junit4]   2> 17639 T57 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-57]: return reader version=7 
reader=StandardDirectoryReader(segments_1:7:nrt)
   [junit4]   2> 17639 T57 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-57]: qtp521133693-57 finishFullFlush success=true
   [junit4]   2> 17639 T57 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-57]: getReader took 22 msec
   [junit4]   2> 17639 T57 C3 oass.SolrIndexSearcher.<init> Opening 
Searcher@5a7c8e16[collection1] realtime
   [junit4]   2> 17640 T57 C3 oasup.LogUpdateProcessor.finish [collection1] 
webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* 
(-1480746192567533568)} 0 25
   [junit4]   2> 17643 T55 C3 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 17644 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: start
   [junit4]   2> 17644 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: enter lock
   [junit4]   2> 17644 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: now prepare
   [junit4]   2> 17644 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: prepareCommit: flush
   [junit4]   2> 17645 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]:   index before flush 
   [junit4]   2> 17645 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: startFullFlush
   [junit4]   2> 17645 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: anyChanges? numDocsInRam=0 deletes=false 
hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 17645 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: apply all deletes during flush
   [junit4]   2> 17645 T55 C3 oasu.LoggingInfoStream.message 
[BD][qtp521133693-55]: prune sis=segments_1:  minGen=9223372036854775807 
packetCount=0
   [junit4]   2> 17646 T55 C3 oasu.LoggingInfoStream.message 
[DW][qtp521133693-55]: qtp521133693-55 finishFullFlush success=true
   [junit4]   2> 17646 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: startCommit(): start
   [junit4]   2> 17646 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: startCommit index= changeCount=9
   [junit4]   2> 17646 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: done all syncs: []
   [junit4]   2> 17647 T55 C3 oasu.LoggingInfoStream.message 
[IW][qtp521133693-55]: commit: pendingCommit != null
   [junit4]   2> 17647 T55 C3 oasu.LoggingInfoStream.message 
[IFD][qtp521133693-55]: now checkpoint "" [0 segments ; isCommit = true]
   [junit4]   2> 17647 T55 C3 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd)),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-62abdabd)),segFN=segments_4,generation=4}
   [junit4]   2> 17647 T55 C3 oasc.SolrDeletionPolicy.updateCommits newest 
commit ge

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

 applyAllDeletes=true
   [junit4]   2> 28573 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]:   index 
before flush _1b(6.0.0):C12
   [junit4]   2> 28573 T61 C7 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: startFullFlush
   [junit4]   2> 28573 T61 C7 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: anyChanges? 
numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 28574 T61 C7 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: 
SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker finishFullFlush 
success=true
   [junit4]   2> 28574 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: apply all 
deletes during flush
   [junit4]   2> 28574 T61 C7 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: applyDeletes: 
no deletes; skipping
   [junit4]   2> 28574 T61 C7 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: prune 
sis=segments_1: _1b(6.0.0):C12 minGen=279 packetCount=0
   [junit4]   2> 28574 T61 C7 oasu.LoggingInfoStream.message 
[CMS][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: now merge
   [junit4]   2> 28575 T61 C7 oasu.LoggingInfoStream.message 
[CMS][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]:   index: 
_1b(6.0.0):C12
   [junit4]   2> 28575 T61 C7 oasu.LoggingInfoStream.message 
[CMS][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]:   no more 
merges pending; now return
   [junit4]   2> 28575 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: waitForMerges
   [junit4]   2> 28575 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: waitForMerges 
done
   [junit4]   2> 28576 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: commit: start
   [junit4]   2> 28576 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: commit: enter 
lock
   [junit4]   2> 28576 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: commit: now 
prepare
   [junit4]   2> 28576 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: 
prepareCommit: flush
   [junit4]   2> 28576 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]:   index 
before flush _1b(6.0.0):C12
   [junit4]   2> 28577 T61 C7 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: startFullFlush
   [junit4]   2> 28577 T61 C7 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: anyChanges? 
numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
   [junit4]   2> 28577 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: apply all 
deletes during flush
   [junit4]   2> 28577 T61 C7 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: applyDeletes: 
no deletes; skipping
   [junit4]   2> 28578 T61 C7 oasu.LoggingInfoStream.message 
[BD][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: prune 
sis=segments_1: _1b(6.0.0):C12 minGen=279 packetCount=0
   [junit4]   2> 28578 T61 C7 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: 
SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker finishFullFlush 
success=true
   [junit4]   2> 28578 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: 
startCommit(): start
   [junit4]   2> 28578 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]:   skip 
startCommit(): no changes pending
   [junit4]   2> 28578 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: commit: 
pendingCommit == null; skip
   [junit4]   2> 28579 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: commit: wrote 
segments file "null"
   [junit4]   2> 28579 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: commit: took 
2.7 msec
   [junit4]   2> 28579 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: commit: done
   [junit4]   2> 28579 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: rollback
   [junit4]   2> 28580 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: all running 
merges have aborted
   [junit4]   2> 28580 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: rollback: 
done finish merges
   [junit4]   2> 28580 T61 C7 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: abort
   [junit4]   2> 28580 T61 C7 oasu.LoggingInfoStream.message 
[DW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: done abort; 
abortedFiles=[] success=true
   [junit4]   2> 28581 T61 C7 oasu.LoggingInfoStream.message 
[IW][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: rollback: 
infos=_1b(6.0.0):C12
   [junit4]   2> 28581 T61 C7 oasu.LoggingInfoStream.message 
[IFD][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: now 
checkpoint "_1b(6.0.0):C12" [1 segments ; isCommit = false]
   [junit4]   2> 28581 T61 C7 oasu.LoggingInfoStream.message 
[IFD][SUITE-SolrExampleBinaryTest-seed#[7F89A5284A18FDF8]-worker]: 0 msec to 
checkpoint
   [junit4]   2> 28582 T61 oasc.SolrCore.closeSearcher [collection1] Closing 
main searcher on request.
   [junit4]   2> 28582 T61 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 28582 T61 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-7F89A5284A18FDF8-001/tempDir-001/index
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-7F89A5284A18FDF8-001/tempDir-001/index;done=false>>]
   [junit4]   2> 28583 T61 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-7F89A5284A18FDF8-001/tempDir-001/index
   [junit4]   2> 28583 T61 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-7F89A5284A18FDF8-001/tempDir-001
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-7F89A5284A18FDF8-001/tempDir-001;done=false>>]
   [junit4]   2> 28583 T61 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-7F89A5284A18FDF8-001/tempDir-001
   [junit4]   2> 28583 T61 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 33560 T61 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleBinaryTest-7F89A5284A18FDF8-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): {}, 
docValues:{}, sim=DefaultSimilarity, locale=bg_BG, timezone=Etc/Zulu
   [junit4]   2> NOTE: Linux 3.13.0-36-generic amd64/Oracle Corporation 
1.7.0_67 (64-bit)/cpus=8,threads=1,free=85123584,total=257949696
   [junit4]   2> NOTE: All tests run in this JVM: [MultiCoreEmbeddedTest, 
FacetFieldTest, TestNamedListCodec, QueryResponseTest, NamedListTest, 
TestFastInputStream, SolrDocumentTest, ConcurrentUpdateSolrServerTest, 
SolrExampleBinaryTest]
   [junit4] Completed on J1 in 14.84s, 28 tests, 1 error <<< FAILURES!

[...truncated 98 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:524: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:472: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:202: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: 
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1359:
 The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:961: 
There were test failures: 53 suites, 314 tests, 2 errors

Total time: 97 minutes 30 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0_67 
-XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


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

Reply via email to