[JENKINS] Lucene-Solr-Tests-trunk-Java7 - Build # 4890 - Failure

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java7/4890/

1 tests failed.
REGRESSION:  org.apache.solr.TestDistributedSearch.testDistribSearch

Error Message:
Request took too long during query expansion. Terminating request.

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Request 
took too long during query expansion. Terminating request.
        at 
__randomizedtesting.SeedInfo.seed([1A495B244AE2419F:9BAFD53C3DBD21A3]: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.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:596)
        at 
org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:499)
        at 
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:875)
        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 11537 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/init-core-data-001
   [junit4]   2> 990756 T1367 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (true)
   [junit4]   2> 990756 T1367 oas.BaseDistributedSearchTestCase.initHostContext 
Setting hostContext system property: /_/
   [junit4]   2> 990788 T1367 oas.SolrTestCaseJ4.setUp ###Starting 
testDistribSearch
   [junit4]   2> 990794 T1367 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 990802 T1367 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:49788
   [junit4]   2> 990803 T1367 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 990804 T1367 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 990804 T1367 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr
   [junit4]   2> 990805 T1367 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/'
   [junit4]   2> 990937 T1367 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/solr.xml
   [junit4]   2> 991072 T1367 oasc.CoreContainer.<init> New CoreContainer 
1156887505
   [junit4]   2> 991073 T1367 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/]
   [junit4]   2> 991074 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 991074 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 991075 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 991075 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 991076 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 991076 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 991076 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 991077 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 991077 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 991078 T1367 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 991079 T1367 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 991079 T1367 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 991079 T1367 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 991087 T1377 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 991090 T1377 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 991090 T1377 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 991091 T1377 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 991204 T1377 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
6.0.0
   [junit4]   2> 991319 T1377 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 991322 T1377 oass.IndexSchema.readSchema Reading Solr Schema 
from schema.xml
   [junit4]   2> 991355 T1377 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 992166 T1377 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 992184 T1377 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 992187 T1377 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 992219 T1377 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 992228 T1377 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 992236 T1377 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 992238 T1377 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 992240 T1377 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 992241 T1377 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 992242 T1377 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 992242 T1377 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 992243 T1377 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 992244 T1377 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 992244 T1377 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/control/data/
   [junit4]   2> 992245 T1377 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@272345a0
   [junit4]   2> 992246 T1377 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/control/data
   [junit4]   2> 992247 T1377 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/control/data/index/
   [junit4]   2> 992247 T1377 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/control/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 992248 T1377 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/control/data/index
   [junit4]   2> 992249 T1377 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=41, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=79.6865234375, 
floorSegmentMB=0.6396484375, forceMergeDeletesPctAllowed=1.7613707650450283, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5856479713636048
   [junit4]   2> 992250 T1377 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-2fa28f15))),segFN=segments_1,generation=1}
   [junit4]   2> 992251 T1377 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 992251 T1377 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 992260 T1377 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 992261 T1377 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 992261 T1377 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 992262 T1377 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 992262 T1377 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 992263 T1377 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 992263 T1377 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 992264 T1377 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 992264 T1377 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 992265 T1377 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 992266 T1377 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 992266 T1377 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 992268 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 992268 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 992269 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 992270 T1377 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 992270 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 992271 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 992272 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
   [junit4]   2> 992272 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created dismax: solr.SearchHandler
   [junit4]   2> 992273 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 992274 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 992275 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 992275 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 992276 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 992276 T1377 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 992277 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 992277 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 992278 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 992278 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 992279 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 992279 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 992280 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak_Direct: 
org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 992281 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 992281 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 992282 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 992283 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 992284 T1377 oasc.RequestHandlers.initHandlersFromConfig 
created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 992330 T1377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 992335 T1377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 992339 T1377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 992343 T1377 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 992346 T1377 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 992356 T1377 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 992357 T1377 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 992361 T1377 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.AlcoholicMergePolicy: 
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1753650853, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 992362 T1377 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-2fa28f15))),segFN=segments_1,generation=1}
   [junit4]   2> 992362 T1377 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 992362 T1377 oass.SolrIndexSearcher.<init> Opening 
Searcher@6e7abf42[collection1] main
   [junit4]   2> 992363 T1377 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 992363 T1377 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 992364 T1377 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 992364 T1377 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 992364 T1377 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 992364 T1377 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 992384 T1377 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 992603 T1378 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6e7abf42[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 992615 T1377 oasc.CoreContainer.registerCore registering core: 
collection1
   [junit4]   2> 992950 T1367 oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2
   [junit4]   2> 992952 T1367 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 992957 T1367 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 992961 T1367 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:63620
   [junit4]   2> 992961 T1367 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 992962 T1367 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 992962 T1367 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr
   [junit4]   2> 992963 T1367 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/'
   [junit4]   2> 993030 T1367 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/solr.xml
   [junit4]   2> 993155 T1367 oasc.CoreContainer.<init> New CoreContainer 
1687377334
   [junit4]   2> 993156 T1367 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/]
   [junit4]   2> 993158 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 993158 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 993159 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 993160 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 993160 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 993161 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 993161 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 993162 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 993162 T1367 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 993163 T1367 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 993164 T1367 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 993164 T1367 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 993165 T1367 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 993174 T1389 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 993177 T1389 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 993177 T1389 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 993178 T1389 oasc.SolrResourceLoader.replaceClassLoader Adding 
'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 993275 T1389 oasc.SolrConfig.<init> Using Lucene MatchVersion: 
6.0.0
   [junit4]   2> 993360 T1389 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 993362 T1389 oass.IndexSchema.readSchema Reading Solr Schema 
from schema.xml
   [junit4]   2> 993384 T1389 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 994025 T1389 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 994049 T1389 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 994055 T1389 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 994078 T1389 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 994087 T1389 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 994096 T1389 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 994098 T1389 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 994099 T1389 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 994099 T1389 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 994101 T1389 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 994101 T1389 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 994102 T1389 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 994102 T1389 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 994103 T1389 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data/
   [junit4]   2> 994103 T1389 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@272345a0
   [junit4]   2> 994105 T1389 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data
   [junit4]   2> 994105 T1389 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data/index/
   [junit4]   2> 994106 T1389 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 994107 T1389 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data/index
   [junit4]   2> 994107 T1389 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=41, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=79.6865234375, 
floorSegmentMB=0.6396484375, forceMergeDeletesPctAllowed=1.7613707650450283, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5856479713636048
   [junit4]   2> 994109 T1389 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-5c87a5a6))),segFN=segments_1,generation=1}
   [junit4]   2> 994109 T1389 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 994110 T1389 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 994121 T1389 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 994122 T1389 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 994123 T1389 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 994123 T1389 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 994123 T1389 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 994124 T1389 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 994124 T1389 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 994125 T1389 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 994126 T1389 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 994126 T1389 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 994127 T1389 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 994127 T1389 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 994129 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 994130 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 994131 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 994131 T1389 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 994132 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 994133 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 994134 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
   [junit4]   2> 994135 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created dismax: solr.SearchHandler
   [junit4]   2> 994137 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 994137 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 994138 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 994139 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 994140 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 994141 T1389 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 994141 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 994142 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 994143 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 994143 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 994144 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 994145 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 994145 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak_Direct: 
org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 994146 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 994147 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 994148 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 994148 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 994149 T1389 oasc.RequestHandlers.initHandlersFromConfig 
created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 994194 T1389 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 994199 T1389 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 994203 T1389 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 994207 T1389 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 994210 T1389 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 994221 T1389 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 994222 T1389 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 994225 T1389 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.AlcoholicMergePolicy: 
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1753650853, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 994226 T1389 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-5c87a5a6))),segFN=segments_1,generation=1}
   [junit4]   2> 994226 T1389 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 994227 T1389 oass.SolrIndexSearcher.<init> Opening 
Searcher@7264c6f7[collection1] main
   [junit4]   2> 994227 T1389 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 994228 T1389 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 994228 T1389 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 994228 T1389 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 994229 T1389 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 994229 T1389 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 994248 T1389 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 994343 T1390 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7264c6f7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 994355 T1389 oasc.CoreContainer.registerCore registering core: 
collection1
   [junit4]   2> 994356 T1367 oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2
   [junit4]   2> 994357 T1367 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C148 name=collection1 
org.apache.solr.core.SolrCore@2a6eb843
   [junit4]   2> 994371 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} 
{deleteByQuery=*:* (-1480448397244628992)} 0 7
   [junit4]   2> ASYNC  NEW_CORE C149 name=collection1 
org.apache.solr.core.SolrCore@73ac157
   [junit4]   2> 994387 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} 
{deleteByQuery=*:* (-1480448397261406208)} 0 8
   [junit4]   2> 994412 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[1 
(1480448397275037696)]} 0 20
   [junit4]   2> 994443 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[1 
(1480448397306494976)]} 0 20
   [junit4]   2> 994459 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[2 
(1480448397341097984)]} 0 4
   [junit4]   2> 994467 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[2 
(1480448397348438016)]} 0 5
   [junit4]   2> 994477 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[3 
(1480448397359972352)]} 0 3
   [junit4]   2> 994489 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[3 
(1480448397371506688)]} 0 5
   [junit4]   2> 994497 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[4 
(1480448397381992448)]} 0 2
   [junit4]   2> 994514 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[4 
(1480448397393526784)]} 0 9
   [junit4]   2> 994528 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[5 
(1480448397412401152)]} 0 5
   [junit4]   2> 994538 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[5 
(1480448397420789760)]} 0 6
   [junit4]   2> 994549 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[6 
(1480448397435469824)]} 0 4
   [junit4]   2> 994561 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[6 
(1480448397447004160)]} 0 5
   [junit4]   2> 994569 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[7 
(1480448397456441344)]} 0 2
   [junit4]   2> 994581 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[7 
(1480448397467975680)]} 0 4
   [junit4]   2> 994596 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[8 
(1480448397483704320)]} 0 4
   [junit4]   2> 994603 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[8 
(1480448397493141504)]} 0 3
   [junit4]   2> 994617 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[9 
(1480448397503627264)]} 0 6
   [junit4]   2> 994629 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[9 
(1480448397517258752)]} 0 6
   [junit4]   2> 994635 T1370 C148 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 994672 T1370 C148 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-2fa28f15))),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-2fa28f15))),segFN=segments_2,generation=2}
   [junit4]   2> 994673 T1370 C148 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 994744 T1370 C148 oass.SolrIndexSearcher.<init> Opening 
Searcher@49cd3216[collection1] main
   [junit4]   2> 994745 T1370 C148 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 994748 T1378 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@49cd3216[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)))}
   [junit4]   2> 994748 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update 
params={softCommit=false&version=2&commit=true&wt=javabin&waitSearcher=true} 
{commit=} 0 113
   [junit4]   2> 994752 T1382 C149 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 994835 T1382 C149 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-5c87a5a6))),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-5c87a5a6))),segFN=segments_2,generation=2}
   [junit4]   2> 994837 T1382 C149 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 994851 T1382 C149 oass.SolrIndexSearcher.<init> Opening 
Searcher@6a1ce70a[collection1] main
   [junit4]   2> 994852 T1382 C149 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 994854 T1390 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6a1ce70a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)))}
   [junit4]   2> 994855 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update 
params={softCommit=false&version=2&commit=true&wt=javabin&waitSearcher=true} 
{commit=} 0 103
   [junit4]   2> 994864 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[10 
(1480448397762625536)]} 0 6
   [junit4]   2> 994874 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[10 
(1480448397773111296)]} 0 6
   [junit4]   2> 994879 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[11 
(1480448397782548480)]} 0 2
   [junit4]   2> 994885 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[11 
(1480448397788839936)]} 0 2
   [junit4]   2> 994893 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[12 
(1480448397796179968)]} 0 2
   [junit4]   2> 994898 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[12 
(1480448397802471424)]} 0 2
   [junit4]   2> 994904 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[13 
(1480448397808762880)]} 0 2
   [junit4]   2> 994910 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[13 
(1480448397815054336)]} 0 2
   [junit4]   2> 994916 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[1001 
(1480448397821345792)]} 0 2
   [junit4]   2> 994921 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[1001 
(1480448397826588672)]} 0 2
   [junit4]   2> 994926 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[14 
(1480448397831831552)]} 0 2
   [junit4]   2> 994931 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[14 
(1480448397837074432)]} 0 2
   [junit4]   2> 994935 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[15 
(1480448397842317312)]} 0 1
   [junit4]   2> 994942 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[15 
(1480448397848608768)]} 0 3
   [junit4]   2> 994948 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[16 
(1480448397854900224)]} 0 2
   [junit4]   2> 994956 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[16 
(1480448397863288832)]} 0 3
   [junit4]   2> 994967 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[17 
(1480448397871677440)]} 0 6
   [junit4]   2> 994978 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[17 
(1480448397883211776)]} 0 5
   [junit4]   2> 994987 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[100 
(1480448397895794688)]} 0 2
   [junit4]   2> 994993 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[100 
(1480448397903134720)]} 0 2
   [junit4]   2> 995001 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[101 
(1480448397910474752)]} 0 3
   [junit4]   2> 995008 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[101 
(1480448397917814784)]} 0 3
   [junit4]   2> 995015 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[102 
(1480448397925154816)]} 0 3
   [junit4]   2> 995021 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[102 
(1480448397932494848)]} 0 2
   [junit4]   2> 995028 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[103 
(1480448397938786304)]} 0 2
   [junit4]   2> 995035 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[103 
(1480448397946126336)]} 0 3
   [junit4]   2> 995043 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[104 
(1480448397954514944)]} 0 3
   [junit4]   2> 995050 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[104 
(1480448397961854976)]} 0 3
   [junit4]   2> 995057 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[105 
(1480448397969195008)]} 0 3
   [junit4]   2> 995064 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[105 
(1480448397976535040)]} 0 3
   [junit4]   2> 995072 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[106 
(1480448397984923648)]} 0 3
   [junit4]   2> 995080 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[106 
(1480448397993312256)]} 0 3
   [junit4]   2> 995088 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[107 
(1480448398001700864)]} 0 3
   [junit4]   2> 995096 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[107 
(1480448398010089472)]} 0 3
   [junit4]   2> 995102 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[108 
(1480448398016380928)]} 0 2
   [junit4]   2> 995109 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[108 
(1480448398023720960)]} 0 3
   [junit4]   2> 995116 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[109 
(1480448398031060992)]} 0 3
   [junit4]   2> 995122 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[109 
(1480448398038401024)]} 0 2
   [junit4]   2> 995128 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[110 
(1480448398044692480)]} 0 2
   [junit4]   2> 995134 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[110 
(1480448398050983936)]} 0 2
   [junit4]   2> 995140 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[111 
(1480448398056226816)]} 0 2
   [junit4]   2> 995146 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[111 
(1480448398062518272)]} 0 2
   [junit4]   2> 995152 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[112 
(1480448398069858304)]} 0 2
   [junit4]   2> 995159 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[112 
(1480448398076149760)]} 0 3
   [junit4]   2> 995166 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[113 
(1480448398083489792)]} 0 3
   [junit4]   2> 995173 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[113 
(1480448398091878400)]} 0 2
   [junit4]   2> 995181 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[114 
(1480448398099218432)]} 0 3
   [junit4]   2> 995188 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[114 
(1480448398106558464)]} 0 3
   [junit4]   2> 995196 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[115 
(1480448398114947072)]} 0 3
   [junit4]   2> 995204 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[115 
(1480448398123335680)]} 0 3
   [junit4]   2> 995211 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[116 
(1480448398130675712)]} 0 3
   [junit4]   2> 995218 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[116 
(1480448398138015744)]} 0 3
   [junit4]   2> 995225 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[117 
(1480448398145355776)]} 0 3
   [junit4]   2> 995232 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[117 
(1480448398152695808)]} 0 3
   [junit4]   2> 995240 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[118 
(1480448398161084416)]} 0 3
   [junit4]   2> 995247 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[118 
(1480448398168424448)]} 0 3
   [junit4]   2> 995254 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[119 
(1480448398175764480)]} 0 3
   [junit4]   2> 995262 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[119 
(1480448398184153088)]} 0 3
   [junit4]   2> 995270 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[120 
(1480448398192541696)]} 0 3
   [junit4]   2> 995277 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[120 
(1480448398199881728)]} 0 3
   [junit4]   2> 995284 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[121 
(1480448398207221760)]} 0 3
   [junit4]   2> 995291 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[121 
(1480448398214561792)]} 0 3
   [junit4]   2> 995299 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[122 
(1480448398222950400)]} 0 3
   [junit4]   2> 995307 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[122 
(1480448398231339008)]} 0 3
   [junit4]   2> 995318 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[123 
(1480448398239727616)]} 0 6
   [junit4]   2> 995325 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[123 
(1480448398250213376)]} 0 2
   [junit4]   2> 995332 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[124 
(1480448398257553408)]} 0 2
   [junit4]   2> 995338 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[124 
(1480448398263844864)]} 0 2
   [junit4]   2> 995343 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[125 
(1480448398270136320)]} 0 1
   [junit4]   2> 995347 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[125 
(1480448398274330624)]} 0 1
   [junit4]   2> 995353 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[126 
(1480448398280622080)]} 0 1
   [junit4]   2> 995358 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[126 
(1480448398285864960)]} 0 1
   [junit4]   2> 995364 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[127 
(1480448398292156416)]} 0 1
   [junit4]   2> 995368 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[127 
(1480448398296350720)]} 0 1
   [junit4]   2> 995375 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[128 
(1480448398302642176)]} 0 2
   [junit4]   2> 995379 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[128 
(1480448398307885056)]} 0 1
   [junit4]   2> 995385 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[129 
(1480448398314176512)]} 0 1
   [junit4]   2> 995389 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[129 
(1480448398318370816)]} 0 1
   [junit4]   2> 995396 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[130 
(1480448398324662272)]} 0 2
   [junit4]   2> 995404 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[130 
(1480448398333050880)]} 0 3
   [junit4]   2> 995412 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[131 
(1480448398341439488)]} 0 3
   [junit4]   2> 995419 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[131 
(1480448398348779520)]} 0 3
   [junit4]   2> 995426 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[132 
(1480448398356119552)]} 0 3
   [junit4]   2> 995433 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[132 
(1480448398363459584)]} 0 3
   [junit4]   2> 995440 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[133 
(1480448398370799616)]} 0 3
   [junit4]   2> 995447 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[133 
(1480448398378139648)]} 0 3
   [junit4]   2> 995454 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[134 
(1480448398385479680)]} 0 3
   [junit4]   2> 995461 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[134 
(1480448398392819712)]} 0 3
   [junit4]   2> 995469 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[135 
(1480448398401208320)]} 0 2
   [junit4]   2> 995476 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[135 
(1480448398408548352)]} 0 2
   [junit4]   2> 995483 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[136 
(1480448398415888384)]} 0 3
   [junit4]   2> 995490 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[136 
(1480448398423228416)]} 0 3
   [junit4]   2> 995497 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[137 
(1480448398430568448)]} 0 3
   [junit4]   2> 995506 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[137 
(1480448398440005632)]} 0 3
   [junit4]   2> 995513 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[138 
(1480448398447345664)]} 0 3
   [junit4]   2> 995520 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[138 
(1480448398454685696)]} 0 2
   [junit4]   2> 995527 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[139 
(1480448398462025728)]} 0 2
   [junit4]   2> 995535 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[139 
(1480448398470414336)]} 0 3
   [junit4]   2> 995543 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[140 
(1480448398478802944)]} 0 3
   [junit4]   2> 995550 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[140 
(1480448398486142976)]} 0 2
   [junit4]   2> 995556 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[141 
(1480448398493483008)]} 0 2
   [junit4]   2> 995562 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[141 
(1480448398499774464)]} 0 2
   [junit4]   2> 995571 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[142 
(1480448398508163072)]} 0 3
   [junit4]   2> 995578 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[142 
(1480448398515503104)]} 0 3
   [junit4]   2> 995585 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[143 
(1480448398522843136)]} 0 2
   [junit4]   2> 995592 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[143 
(1480448398530183168)]} 0 3
   [junit4]   2> 995600 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[144 
(1480448398538571776)]} 0 3
   [junit4]   2> 995607 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[144 
(1480448398545911808)]} 0 3
   [junit4]   2> 995613 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[145 
(1480448398553251840)]} 0 2
   [junit4]   2> 995621 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[145 
(1480448398561640448)]} 0 1
   [junit4]   2> 995626 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[146 
(1480448398566883328)]} 0 1
   [junit4]   2> 995632 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[146 
(1480448398573174784)]} 0 2
   [junit4]   2> 995639 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[147 
(1480448398580514816)]} 0 2
   [junit4]   2> 995646 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[147 
(1480448398586806272)]} 0 2
   [junit4]   2> 995653 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[148 
(1480448398594146304)]} 0 3
   [junit4]   2> 995660 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[148 
(1480448398601486336)]} 0 3
   [junit4]   2> 995667 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[149 
(1480448398608826368)]} 0 3
   [junit4]   2> 995674 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update params={version=2&wt=javabin} {add=[149 
(1480448398616166400)]} 0 3
   [junit4]   2> 995678 T1370 C148 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 995826 T1370 C148 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-2fa28f15))),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-2fa28f15))),segFN=segments_3,generation=3}
   [junit4]   2> 995827 T1370 C148 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 995890 T1370 C148 oass.SolrIndexSearcher.<init> Opening 
Searcher@6127c65c[collection1] main
   [junit4]   2> 995892 T1370 C148 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 995895 T1378 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@6127c65c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)
 Uninverting(_1(6.0.0):C59)))}
   [junit4]   2> 995896 T1370 C148 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update 
params={softCommit=false&version=2&commit=true&wt=javabin&waitSearcher=true} 
{commit=} 0 218
   [junit4]   2> 995901 T1382 C149 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 995985 T1382 C149 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-5c87a5a6))),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-5c87a5a6))),segFN=segments_3,generation=3}
   [junit4]   2> 995986 T1382 C149 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 996020 T1382 C149 oass.SolrIndexSearcher.<init> Opening 
Searcher@d0892[collection1] main
   [junit4]   2> 996022 T1382 C149 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 996024 T1390 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@d0892[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)
 Uninverting(_1(6.0.0):C59)))}
   [junit4]   2> 996025 T1382 C149 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/_ path=/update 
params={softCommit=false&version=2&commit=true&wt=javabin&waitSearcher=true} 
{commit=} 0 124
   [junit4]   2> 996138 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_ti1+desc} hits=68 
status=0 QTime=108 
   [junit4]   2> 996235 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::114]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615001&wt=javabin&isShard=true&sort=n_ti1+desc}
 hits=68 status=0 QTime=61 
   [junit4]   2> 996368 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=8,9,125,124,1001,14,102,117,130,131&q=*:*&shard.url=[ff01::114]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_&distrib=false&NOW=1411865615001&wt=javabin&isShard=true}
 status=0 QTime=95 
   [junit4]   2> 996378 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::114]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_&wt=javabin&sort=n_ti1+desc}
 hits=68 status=0 QTime=230 
   [junit4]   2> 996397 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_ti1+asc} hits=68 
status=0 QTime=1 
   [junit4]   2> 996414 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_|[ff01::083]:33332/_&distrib=false&start=0&NOW=1411865615260&wt=javabin&isShard=true&sort=n_ti1+asc}
 hits=68 status=0 QTime=1 
   [junit4]   2> 996461 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=144,140,112,135,105,139,129,119,3,123&q=*:*&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_|[ff01::083]:33332/_&distrib=false&NOW=1411865615260&wt=javabin&isShard=true}
 status=0 QTime=2 
   [junit4]   2> 996470 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::213]:33332/_|http://127.0.0.1:63620/_|[ff01::083]:33332/_&wt=javabin&sort=n_ti1+asc}
 hits=68 status=0 QTime=63 
   [junit4]   2> 996479 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_f1+desc} hits=68 
status=0 QTime=3 
   [junit4]   2> 996496 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=http://127.0.0.1:63620/_|[ff01::213]:33332/_&distrib=false&start=0&NOW=1411865615340&wt=javabin&isShard=true&sort=n_f1+desc}
 hits=68 status=0 QTime=3 
   [junit4]   2> 996504 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=112,135,124,108,120,10,104,141,121,122&q=*:*&shard.url=http://127.0.0.1:63620/_|[ff01::213]:33332/_&distrib=false&NOW=1411865615340&wt=javabin&isShard=true}
 status=0 QTime=2 
   [junit4]   2> 996510 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=http://127.0.0.1:63620/_|[ff01::213]:33332/_&wt=javabin&sort=n_f1+desc}
 hits=68 status=0 QTime=23 
   [junit4]   2> 996519 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_f1+asc} hits=68 
status=0 QTime=2 
   [junit4]   2> 996530 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615379&wt=javabin&isShard=true&sort=n_f1+asc}
 hits=68 status=0 QTime=1 
   [junit4]   2> 996540 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=116,134,11,132,147,133,102,129,3,123&q=*:*&shard.url=http://127.0.0.1:63620/_&distrib=false&NOW=1411865615379&wt=javabin&isShard=true}
 status=0 QTime=2 
   [junit4]   2> 996549 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=http://127.0.0.1:63620/_&wt=javabin&sort=n_f1+asc}
 hits=68 status=0 QTime=22 
   [junit4]   2> 996592 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_tf1+desc} hits=68 
status=0 QTime=22 
   [junit4]   2> 996604 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615453&wt=javabin&isShard=true&sort=n_tf1+desc}
 hits=68 status=0 QTime=1 
   [junit4]   2> 996612 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=116,134,106,135,1,102,137,121,119,149&q=*:*&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_&distrib=false&NOW=1411865615453&wt=javabin&isShard=true}
 status=0 QTime=2 
   [junit4]   2> 996616 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::213]:33332/_|http://127.0.0.1:63620/_&wt=javabin&sort=n_tf1+desc}
 hits=68 status=0 QTime=16 
   [junit4]   2> 996625 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_tf1+asc} hits=68 
status=0 QTime=1 
   [junit4]   2> 996635 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::213]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_&distrib=false&start=0&NOW=1411865615484&wt=javabin&isShard=true&sort=n_tf1+asc}
 hits=68 status=0 QTime=1 
   [junit4]   2> 996644 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=8,132,15,2,111,12,105,120,117,3&q=*:*&shard.url=[ff01::213]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_&distrib=false&NOW=1411865615484&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 996651 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::213]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_&wt=javabin&sort=n_tf1+asc}
 hits=68 status=0 QTime=20 
   [junit4]   2> 996663 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_d1+desc} hits=68 
status=0 QTime=2 
   [junit4]   2> 996678 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::213]:33332/_|[ff01::114]:33332/_&distrib=false&start=0&NOW=1411865615521&wt=javabin&isShard=true&sort=n_d1+desc}
 hits=68 status=0 QTime=3 
   [junit4]   2> 996686 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=2,1,105,12,145,1001,104,127,143,149&q=*:*&shard.url=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::213]:33332/_|[ff01::114]:33332/_&distrib=false&NOW=1411865615521&wt=javabin&isShard=true}
 status=0 QTime=2 
   [junit4]   2> 996690 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::213]:33332/_|[ff01::114]:33332/_&wt=javabin&sort=n_d1+desc}
 hits=68 status=0 QTime=22 
   [junit4]   2> 996700 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_d1+asc} hits=68 
status=0 QTime=1 
   [junit4]   2> 996709 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615558&wt=javabin&isShard=true&sort=n_d1+asc}
 hits=68 status=0 QTime=1 
   [junit4]   2> 996720 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=116,5,125,140,126,124,139,100,10,119&q=*:*&shard.url=http://127.0.0.1:63620/_&distrib=false&NOW=1411865615558&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 996724 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=http://127.0.0.1:63620/_&wt=javabin&sort=n_d1+asc}
 hits=68 status=0 QTime=19 
   [junit4]   2> 996741 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_td1+desc} hits=68 
status=0 QTime=5 
   [junit4]   2> 996756 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::114]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615600&wt=javabin&isShard=true&sort=n_td1+desc}
 hits=68 status=0 QTime=5 
   [junit4]   2> 996769 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=5,15,111,124,1001,114,146,133,129,137&q=*:*&shard.url=[ff01::114]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_&distrib=false&NOW=1411865615600&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 996774 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::114]:33332/_|[ff01::083]:33332/_|http://127.0.0.1:63620/_&wt=javabin&sort=n_td1+desc}
 hits=68 status=0 QTime=27 
   [junit4]   2> 996783 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_td1+asc} hits=68 
status=0 QTime=1 
   [junit4]   2> 996797 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=http://127.0.0.1:63620/_|[ff01::213]:33332/_&distrib=false&start=0&NOW=1411865615643&wt=javabin&isShard=true&sort=n_td1+asc}
 hits=68 status=0 QTime=2 
   [junit4]   2> 996806 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=134,126,7,138,145,139,102,109,143,149&q=*:*&shard.url=http://127.0.0.1:63620/_|[ff01::213]:33332/_&distrib=false&NOW=1411865615643&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 996811 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=http://127.0.0.1:63620/_|[ff01::213]:33332/_&wt=javabin&sort=n_td1+asc}
 hits=68 status=0 QTime=21 
   [junit4]   2> 996822 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_l1+desc} hits=68 
status=0 QTime=2 
   [junit4]   2> 996836 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::213]:33332/_&distrib=false&start=0&NOW=1411865615682&wt=javabin&isShard=true&sort=n_l1+desc}
 hits=68 status=0 QTime=3 
   [junit4]   2> 996844 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=13,140,2,126,4,145,1001,100,121,149&q=*:*&shard.url=http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::213]:33332/_&distrib=false&NOW=1411865615682&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 996848 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::213]:33332/_&wt=javabin&sort=n_l1+desc}
 hits=68 status=0 QTime=19 
   [junit4]   2> 996860 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_l1+asc} hits=68 
status=0 QTime=1 
   [junit4]   2> 996871 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615718&wt=javabin&isShard=true&sort=n_l1+asc}
 hits=68 status=0 QTime=2 
   [junit4]   2> 996879 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=116,9,15,136,1,120,146,17,141,130&q=*:*&shard.url=http://127.0.0.1:63620/_&distrib=false&NOW=1411865615718&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 996885 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=http://127.0.0.1:63620/_&wt=javabin&sort=n_l1+asc}
 hits=68 status=0 QTime=20 
   [junit4]   2> 996899 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_tl1+desc} hits=68 
status=0 QTime=4 
   [junit4]   2> 996914 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::213]:33332/_&distrib=false&start=0&NOW=1411865615758&wt=javabin&isShard=true&sort=n_tl1+desc}
 hits=68 status=0 QTime=5 
   [junit4]   2> 996922 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=144,106,4,146,102,118,137,121,130,3&q=*:*&shard.url=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::213]:33332/_&distrib=false&NOW=1411865615758&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 996926 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::213]:33332/_&wt=javabin&sort=n_tl1+desc}
 hits=68 status=0 QTime=21 
   [junit4]   2> 996937 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_tl1+asc} hits=68 
status=0 QTime=2 
   [junit4]   2> 996948 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615795&wt=javabin&isShard=true&sort=n_tl1+asc}
 hits=68 status=0 QTime=2 
   [junit4]   2> 996956 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=110,13,135,7,124,12,109,129,127,143&q=*:*&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_&distrib=false&NOW=1411865615795&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 996960 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::213]:33332/_|http://127.0.0.1:63620/_&wt=javabin&sort=n_tl1+asc}
 hits=68 status=0 QTime=18 
   [junit4]   2> 996976 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_dt1+desc} hits=68 
status=0 QTime=4 
   [junit4]   2> 996991 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::083]:33332/_|[ff01::213]:33332/_&distrib=false&start=0&NOW=1411865615835&wt=javabin&isShard=true&sort=n_dt1+desc}
 hits=68 status=0 QTime=4 
   [junit4]   2> 997001 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=13,101,132,112,1,139,102,137,131,123&q=*:*&shard.url=http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::083]:33332/_|[ff01::213]:33332/_&distrib=false&NOW=1411865615835&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 997005 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::083]:33332/_|[ff01::213]:33332/_&wt=javabin&sort=n_dt1+desc}
 hits=68 status=0 QTime=23 
   [junit4]   2> 997025 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_dt1+asc} hits=68 
status=0 QTime=2 
   [junit4]   2> 997037 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615883&wt=javabin&isShard=true&sort=n_dt1+asc}
 hits=68 status=0 QTime=2 
   [junit4]   2> 997046 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=11,9,108,4,120,147,114,100,118,115&q=*:*&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_&distrib=false&NOW=1411865615883&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 997049 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::213]:33332/_|http://127.0.0.1:63620/_&wt=javabin&sort=n_dt1+asc}
 hits=68 status=0 QTime=19 
   [junit4]   2> 997065 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_tdt1+desc} hits=68 
status=0 QTime=2 
   [junit4]   2> 997078 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865615924&wt=javabin&isShard=true&sort=n_tdt1+desc}
 hits=68 status=0 QTime=3 
   [junit4]   2> 997087 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=11,106,4,147,14,146,109,121,3,131&q=*:*&shard.url=http://127.0.0.1:63620/_&distrib=false&NOW=1411865615924&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 997090 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=http://127.0.0.1:63620/_&wt=javabin&sort=n_tdt1+desc}
 hits=68 status=0 QTime=19 
   [junit4]   2> 997110 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=n_tdt1+asc} hits=68 
status=0 QTime=2 
   [junit4]   2> 997122 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_&distrib=false&start=0&NOW=1411865615969&wt=javabin&isShard=true&sort=n_tdt1+asc}
 hits=68 status=0 QTime=2 
   [junit4]   2> 997131 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=8,111,138,16,120,113,128,17,141,142&q=*:*&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_&distrib=false&NOW=1411865615969&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 997135 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::213]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_&wt=javabin&sort=n_tdt1+asc}
 hits=68 status=0 QTime=19 
   [junit4]   2> 997149 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=a_i1+desc} hits=68 
status=0 QTime=1 
   [junit4]   2> 997160 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_&distrib=false&start=0&NOW=1411865616007&wt=javabin&isShard=true&sort=a_i1+desc}
 hits=68 status=0 QTime=2 
   [junit4]   2> 997168 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=8,5,13,9,2,7,1,12,10,3&q=*:*&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_&distrib=false&NOW=1411865616007&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 997170 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::213]:33332/_|http://127.0.0.1:63620/_&wt=javabin&sort=a_i1+desc}
 hits=68 status=0 QTime=16 
   [junit4]   2> 997327 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort={!func}testfunc(add(a_i1,5))+desc}
 hits=68 status=0 QTime=138 
   [junit4]   2> 997339 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::114]:33332/_|http://127.0.0.1:63620/_|[ff01::083]:33332/_|[ff01::213]:33332/_&distrib=false&start=0&NOW=1411865616186&wt=javabin&isShard=true&sort={!func}testfunc(add(a_i1,5))+desc}
 hits=68 status=0 QTime=2 
   [junit4]   2> 997348 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=8,5,13,9,2,7,1,12,10,3&q=*:*&shard.url=[ff01::114]:33332/_|http://127.0.0.1:63620/_|[ff01::083]:33332/_|[ff01::213]:33332/_&distrib=false&NOW=1411865616186&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 997352 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::114]:33332/_|http://127.0.0.1:63620/_|[ff01::083]:33332/_|[ff01::213]:33332/_&wt=javabin&sort={!func}testfunc(add(a_i1,5))+desc}
 hits=68 status=0 QTime=19 
   [junit4]   2> 997369 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&distrib=false&wt=javabin&sort=a_i1+asc} hits=68 
status=0 QTime=2 
   [junit4]   2> 997380 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id&shard.url=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::213]:33332/_|[ff01::114]:33332/_&distrib=false&start=0&NOW=1411865616227&wt=javabin&isShard=true&sort=a_i1+asc}
 hits=68 status=0 QTime=1 
   [junit4]   2> 997389 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=13,11,9,6,2,1,7,4,12,3&q=*:*&shard.url=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::213]:33332/_|[ff01::114]:33332/_&distrib=false&NOW=1411865616227&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 997392 T1382 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&shards=[ff01::083]:33332/_|http://127.0.0.1:63620/_|[ff01::213]:33332/_|[ff01::114]:33332/_&wt=javabin&sort=a_i1+asc}
 hits=68 status=0 QTime=18 
   [junit4]   2> 997402 T1370 C148 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&q=*:*&fl=*,score&distrib=false&wt=javabin&sort=a_i1+desc} 
hits=68 status=0 QTime=2 
   [junit4]   2> 997417 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&rows=10&fsv=true&q=*:*&fl=id,score&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::083]:33332/_&distrib=false&start=0&NOW=1411865616263&wt=javabin&isShard=true&sort=a_i1+desc}
 hits=68 status=0 QTime=2 
   [junit4]   2> 997425 T1383 C149 oasc.SolrCore.execute [collection1] 
webapp=/_ path=/select 
params={version=2&ids=8,5,13,9,2,7,1,12,10,3&q=*:*&fl=*,score&shard.url=[ff01::213]:33332/_|http://127.0.0.1:63620/_|[ff01::114]:33332/_|[ff01::083]:33332/_&distrib=false&NOW=1411865616263&wt=javabin&isShard=true}
 status=0 QTime=3 
   [junit4]   2> 9

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

1032558 T1367 oasc.CachingDirectoryFactory.closeCacheValue looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data;done=false>>]
   [junit4]   2> 1032558 T1367 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data
   [junit4]   2> 1032559 T1367 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data/index;done=false>>]
   [junit4]   2> 1032559 T1367 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard0/data/index
   [junit4]   2> 1032560 T1367 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/_,null}
   [junit4]   2> 1037537 T1367 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=340801030
   [junit4]   2> 1037539 T1367 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@22fa0be
   [junit4]   2> 1037566 T1367 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=3,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=36,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=8913,transaction_logs_total_number=3}
   [junit4]   2> 1037567 T1367 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1037568 T1367 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1037568 T1367 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1037573 T1367 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1037574 T1367 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1037575 T1367 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard1/data/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard1/data/index;done=false>>]
   [junit4]   2> 1037576 T1367 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard1/data/index
   [junit4]   2> 1037576 T1367 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard1/data
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard1/data;done=false>>]
   [junit4]   2> 1037576 T1367 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001/tempDir-001/shard1/data
   [junit4]   2> 1037577 T1367 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/_,null}
   [junit4]   2> 1042565 T1367 oas.SolrTestCaseJ4.tearDown ###Ending 
testDistribSearch
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=testDistribSearch 
-Dtests.seed=1A495B244AE2419F -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=ar_LB -Dtests.timezone=Canada/Pacific -Dtests.file.encoding=UTF-8
   [junit4] ERROR   51.8s J2 | TestDistributedSearch.testDistribSearch <<<
   [junit4]    > Throwable #1: 
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Request 
took too long during query expansion. Terminating request.
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([1A495B244AE2419F:9BAFD53C3DBD21A3]: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.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:596)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:499)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:875)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1042584 T1367 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch-1A495B244AE2419F-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): 
{n_tf1=PostingsFormat(name=Direct), rnd_b=PostingsFormat(name=SimpleText), 
timestamp=PostingsFormat(name=Direct), 
range_facet_l=PostingsFormat(name=Direct), n_l1=PostingsFormat(name=Direct), 
intDefault=PostingsFormat(name=Direct), 
a_n_tdt=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))),
 _version_=PostingsFormat(name=Direct), lowerfilt1and2=FSTOrd41, 
n_ti1=PostingsFormat(name=SimpleText), lowerfilt=FSTOrd41, n_f1=FSTOrd41, 
n_dt1=FSTOrd41, 
text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))),
 a_t=PostingsFormat(name=Direct), n_tdt1=PostingsFormat(name=Direct), 
n_tl1=FSTOrd41, 
foo_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))),
 n_d1=PostingsFormat(name=Direct), foo_d=PostingsFormat(name=SimpleText), 
foo_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))),
 multiDefault=FSTOrd41, SubjectTerms_mfacet=PostingsFormat(name=Direct), 
a_i1=PostingsFormat(name=Direct), id=PostingsFormat(name=SimpleText), 
n_td1=FSTOrd41, 
oddField_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene41(blocksize=128))),
 b_n_tdt=PostingsFormat(name=Direct), other_tl1=PostingsFormat(name=Direct)}, 
docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=DefaultSimilarity, 
locale=ar_LB, timezone=Canada/Pacific
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 
(64-bit)/cpus=16,threads=1,free=173116632,total=236978176
   [junit4]   2> NOTE: All tests run in this JVM: [OverseerStatusTest, 
SyncSliceTest, CopyFieldTest, CollectionsAPIAsyncDistributedZkTest, 
TestReplicationHandler, AnalysisAfterCoreReloadTest, PingRequestHandlerTest, 
DistributedExpandComponentTest, UUIDUpdateProcessorFallbackTest, 
HdfsChaosMonkeySafeLeaderTest, ZkNodePropsTest, TestCloudInspectUtil, 
TestDistributedSearch]
   [junit4] Completed on J2 in 51.93s, 1 test, 1 error <<< FAILURES!

[...truncated 823 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:524:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:472:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:61:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/extra-targets.xml:39:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build.xml:189:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/common-build.xml:496:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:1359:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:961:
 There were test failures: 432 suites, 1778 tests, 1 error, 58 ignored (23 
assumptions)

Total time: 61 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-trunk-Java7 #4889
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 154202918 bytes
Compression is 0.0%
Took 27 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org