Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11221/
Java: 32bit/jdk1.7.0_67 -client -XX:+UseSerialGC

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([377AFD4F005F159A:B69C7357770075A6]: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 11268 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/init-core-data-001
   [junit4]   2> 1481058 T3744 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(true) and clientAuth (true)
   [junit4]   2> 1481058 T3744 
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system 
property: /
   [junit4]   2> 1481060 T3744 oas.SolrTestCaseJ4.setUp ###Starting 
testDistribSearch
   [junit4]   2> 1481061 T3744 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1481064 T3744 oejus.SslContextFactory.doStart Enabled 
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, 
TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 1481065 T3744 oejs.AbstractConnector.doStart Started 
SslSelectChannelConnector@127.0.0.1:41356
   [junit4]   2> 1481066 T3744 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 1481067 T3744 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1481067 T3744 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 1481067 T3744 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
   [junit4]   2> 1481079 T3744 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/solr.xml
   [junit4]   2> 1481108 T3744 oasc.CoreContainer.<init> New CoreContainer 
25004237
   [junit4]   2> 1481108 T3744 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
   [junit4]   2> 1481109 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 1481109 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: https
   [junit4]   2> 1481110 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 1481110 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1481110 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1481110 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1481111 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1481111 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1481111 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1481113 T3744 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 1481114 T3744 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1481114 T3744 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1481114 T3744 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1481117 T3754 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1481119 T3754 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 1481119 T3754 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1481120 T3754 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1481168 T3754 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 1481240 T3754 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1481241 T3754 oass.IndexSchema.readSchema Reading Solr Schema 
from schema.xml
   [junit4]   2> 1481253 T3754 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1481540 T3754 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1481545 T3754 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1481547 T3754 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1481552 T3754 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1481554 T3754 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1481556 T3754 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1481557 T3754 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1481557 T3754 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1481557 T3754 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1481557 T3754 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1481558 T3754 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1481558 T3754 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 1481558 T3754 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1481558 T3754 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/,
 
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/control/data/
   [junit4]   2> 1481558 T3754 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@213bc1
   [junit4]   2> 1481559 T3754 oasc.CachingDirectoryFactory.get return new 
directory for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/control/data
   [junit4]   2> 1481559 T3754 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/control/data/index/
   [junit4]   2> 1481559 T3754 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/control/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1481560 T3754 oasc.CachingDirectoryFactory.get return new 
directory for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/control/data/index
   [junit4]   2> 1481560 T3754 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=48.71875, 
floorSegmentMB=1.822265625, forceMergeDeletesPctAllowed=15.657562124817371, 
segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1481561 T3754 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-1a4e0b1))),segFN=segments_1,generation=1}
   [junit4]   2> 1481561 T3754 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1481561 T3754 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 1481563 T3754 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1481564 T3754 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1481564 T3754 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1481564 T3754 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1481564 T3754 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1481564 T3754 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1481565 T3754 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1481565 T3754 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1481565 T3754 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1481565 T3754 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1481566 T3754 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1481566 T3754 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1481566 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1481566 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1481567 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1481567 T3754 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1481578 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 1481578 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 1481579 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
   [junit4]   2> 1481579 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created dismax: solr.SearchHandler
   [junit4]   2> 1481580 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 1481580 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1481581 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1481581 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1481582 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 1481582 T3754 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1481583 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 1481583 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 1481584 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1481585 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1481585 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1481586 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1481586 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak_Direct: 
org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1481587 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1481587 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1481587 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1481588 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 1481588 T3754 oasc.RequestHandlers.initHandlersFromConfig 
created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 1481591 T3754 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1481593 T3754 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1481595 T3754 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1481596 T3754 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1481597 T3754 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 1481599 T3754 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1481599 T3754 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1481600 T3754 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1481600 T3754 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-1a4e0b1))),segFN=segments_1,generation=1}
   [junit4]   2> 1481601 T3754 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1481601 T3754 oass.SolrIndexSearcher.<init> Opening 
Searcher@4cb760[collection1] main
   [junit4]   2> 1481601 T3754 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1481601 T3754 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 1481602 T3754 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 1481602 T3754 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 1481602 T3754 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1481602 T3754 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 1488812 T3754 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1488833 T3755 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@4cb760[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1488838 T3754 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1488838 T3744 oass.SolrDispatchFilter.init 
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 1488839 T3744 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 1488841 T3744 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1488844 T3744 oejus.SslContextFactory.doStart Enabled 
Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, 
TLSv1, TLSv1.1, TLSv1.2]
   [junit4]   2> 1488846 T3744 oejs.AbstractConnector.doStart Started 
SslSelectChannelConnector@127.0.0.1:33748
   [junit4]   2> 1488848 T3744 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 1488848 T3744 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1488849 T3744 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 1488849 T3744 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
   [junit4]   2> 1488862 T3744 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/solr.xml
   [junit4]   2> 1488891 T3744 oasc.CoreContainer.<init> New CoreContainer 
16485932
   [junit4]   2> 1488891 T3744 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
   [junit4]   2> 1488892 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 1488892 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: https
   [junit4]   2> 1488893 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 1488893 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1488893 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1488893 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1488894 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1488894 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1488894 T3744 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1488896 T3744 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 1488897 T3744 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1488897 T3744 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1488897 T3744 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1488900 T3766 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 1488900 T3766 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 1488901 T3766 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 1488901 T3766 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 1488948 T3766 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 1488994 T3766 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1488994 T3766 oass.IndexSchema.readSchema Reading Solr Schema 
from schema.xml
   [junit4]   2> 1489002 T3766 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1489248 T3766 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1489253 T3766 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 1489255 T3766 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1489270 T3766 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1489273 T3766 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 1489275 T3766 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1489275 T3766 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1489276 T3766 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1489276 T3766 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1489276 T3766 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 1489277 T3766 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 1489277 T3766 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 1489277 T3766 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1489277 T3766 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/,
 
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard0/data/
   [junit4]   2> 1489277 T3766 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@213bc1
   [junit4]   2> 1489278 T3766 oasc.CachingDirectoryFactory.get return new 
directory for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard0/data
   [junit4]   2> 1489278 T3766 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard0/data/index/
   [junit4]   2> 1489278 T3766 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard0/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1489279 T3766 oasc.CachingDirectoryFactory.get return new 
directory for 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard0/data/index
   [junit4]   2> 1489279 T3766 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=48.71875, 
floorSegmentMB=1.822265625, forceMergeDeletesPctAllowed=15.657562124817371, 
segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1489279 T3766 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-be4e84))),segFN=segments_1,generation=1}
   [junit4]   2> 1489280 T3766 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1489280 T3766 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 1489283 T3766 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 1489283 T3766 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1489283 T3766 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1489283 T3766 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 1489284 T3766 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1489284 T3766 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1489284 T3766 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1489284 T3766 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1489284 T3766 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1489285 T3766 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1489285 T3766 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1489285 T3766 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1489286 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1489286 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1489287 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1489287 T3766 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 1489287 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 1489288 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 1489288 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
   [junit4]   2> 1489289 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created dismax: solr.SearchHandler
   [junit4]   2> 1489289 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 1489290 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 1489290 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 1489291 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 1489291 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 1489291 T3766 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1489291 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 1489292 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 1489292 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1489293 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1489293 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1489294 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1489294 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak_Direct: 
org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1489295 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1489295 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1489296 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 1489297 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 1489297 T3766 oasc.RequestHandlers.initHandlersFromConfig 
created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 1489299 T3766 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1489301 T3766 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1489302 T3766 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1489303 T3766 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1489304 T3766 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 1489306 T3766 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1489306 T3766 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1489307 T3766 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=29, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1489307 T3766 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-be4e84))),segFN=segments_1,generation=1}
   [junit4]   2> 1489307 T3766 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1489307 T3766 oass.SolrIndexSearcher.<init> Opening 
Searcher@156aded[collection1] main
   [junit4]   2> 1489308 T3766 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1489308 T3766 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 1489308 T3766 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 1489308 T3766 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 1489309 T3766 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1489309 T3766 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 1489313 T3766 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1489332 T3767 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@156aded[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1489339 T3766 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1489340 T3744 oass.SolrDispatchFilter.init 
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 1489340 T3744 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C1789 name=collection1 
org.apache.solr.core.SolrCore@1aa2d04
   [junit4]   2> 1489463 T3750 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} 
{deleteByQuery=*:* (-1480910358229024768)} 0 1
   [junit4]   2> ASYNC  NEW_CORE C1790 name=collection1 
org.apache.solr.core.SolrCore@1a582d1
   [junit4]   2> 1489596 T3762 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} 
{deleteByQuery=*:* (-1480910358368485376)} 0 1
   [junit4]   2> 1489603 T3751 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[1 
(1480910358374776832)]} 0 2
   [junit4]   2> 1489608 T3762 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[1 
(1480910358381068288)]} 0 1
   [junit4]   2> 1489613 T3750 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[2 
(1480910358387359744)]} 0 0
   [junit4]   2> 1489618 T3765 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[2 
(1480910358391554048)]} 0 1
   [junit4]   2> 1489622 T3750 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[3 
(1480910358396796928)]} 0 0
   [junit4]   2> 1489627 T3764 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[3 
(1480910358402039808)]} 0 1
   [junit4]   2> 1489632 T3750 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[4 
(1480910358407282688)]} 0 1
   [junit4]   2> 1489638 T3764 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[4 
(1480910358412525568)]} 0 1
   [junit4]   2> 1489642 T3753 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[5 
(1480910358417768448)]} 0 0
   [junit4]   2> 1489647 T3763 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[5 
(1480910358423011328)]} 0 1
   [junit4]   2> 1489652 T3752 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[6 
(1480910358428254208)]} 0 1
   [junit4]   2> 1489656 T3762 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[6 
(1480910358432448512)]} 0 0
   [junit4]   2> 1489661 T3752 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[7 
(1480910358436642816)]} 0 1
   [junit4]   2> 1489665 T3765 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[7 
(1480910358441885696)]} 0 0
   [junit4]   2> 1489670 T3751 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[8 
(1480910358446080000)]} 0 1
   [junit4]   2> 1489674 T3764 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[8 
(1480910358451322880)]} 0 0
   [junit4]   2> 1489679 T3750 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[9 
(1480910358455517184)]} 0 1
   [junit4]   2> 1489683 T3763 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[9 
(1480910358460760064)]} 0 0
   [junit4]   2> 1489687 T3753 C1789 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1489695 T3753 C1789 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-1a4e0b1))),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-1a4e0b1))),segFN=segments_2,generation=2}
   [junit4]   2> 1489696 T3753 C1789 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 2
   [junit4]   2> 1489698 T3753 C1789 oass.SolrIndexSearcher.<init> Opening 
Searcher@1dddfb[collection1] main
   [junit4]   2> 1489698 T3753 C1789 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1489700 T3755 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1dddfb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c9)))}
   [junit4]   2> 1489701 T3753 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update 
params={version=2&waitSearcher=true&softCommit=false&wt=javabin&commit=true} 
{commit=} 0 14
   [junit4]   2> 1489704 T3763 C1790 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1489711 T3763 C1790 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-be4e84))),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-be4e84))),segFN=segments_2,generation=2}
   [junit4]   2> 1489712 T3763 C1790 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 2
   [junit4]   2> 1489714 T3763 C1790 oass.SolrIndexSearcher.<init> Opening 
Searcher@1e662b2[collection1] main
   [junit4]   2> 1489715 T3763 C1790 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1489717 T3767 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1e662b2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c9)))}
   [junit4]   2> 1489717 T3763 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update 
params={version=2&waitSearcher=true&softCommit=false&wt=javabin&commit=true} 
{commit=} 0 13
   [junit4]   2> 1489724 T3752 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[10 
(1480910358500605952)]} 0 3
   [junit4]   2> 1489729 T3762 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[10 
(1480910358507945984)]} 0 1
   [junit4]   2> 1489734 T3751 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[11 
(1480910358513188864)]} 0 1
   [junit4]   2> 1489738 T3765 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[11 
(1480910358518431744)]} 0 0
   [junit4]   2> 1489742 T3752 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[12 
(1480910358522626048)]} 0 0
   [junit4]   2> 1489747 T3764 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[12 
(1480910358526820352)]} 0 0
   [junit4]   2> 1489751 T3752 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[13 
(1480910358531014656)]} 0 1
   [junit4]   2> 1489755 T3763 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[13 
(1480910358536257536)]} 0 0
   [junit4]   2> 1489760 T3751 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[1001 
(1480910358540451840)]} 0 1
   [junit4]   2> 1489764 T3762 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[1001 
(1480910358545694720)]} 0 0
   [junit4]   2> 1489769 T3753 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[14 
(1480910358549889024)]} 0 1
   [junit4]   2> 1489773 T3763 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[14 
(1480910358555131904)]} 0 1
   [junit4]   2> 1489777 T3753 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[15 
(1480910358559326208)]} 0 0
   [junit4]   2> 1489782 T3765 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[15 
(1480910358563520512)]} 0 1
   [junit4]   2> 1489786 T3752 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[16 
(1480910358567714816)]} 0 1
   [junit4]   2> 1489790 T3764 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[16 
(1480910358571909120)]} 0 1
   [junit4]   2> 1489795 T3753 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[17 
(1480910358578200576)]} 0 1
   [junit4]   2> 1489801 T3764 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[17 
(1480910358583443456)]} 0 2
   [junit4]   2> 1489804 T3752 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[100 
(1480910358587637760)]} 0 0
   [junit4]   2> 1489808 T3765 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[100 
(1480910358591832064)]} 0 0
   [junit4]   2> 1489812 T3752 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[101 
(1480910358596026368)]} 0 0
   [junit4]   2> 1489816 T3763 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[101 
(1480910358600220672)]} 0 0
   [junit4]   2> 1489820 T3751 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[102 
(1480910358604414976)]} 0 0
   [junit4]   2> 1489824 T3763 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[102 
(1480910358608609280)]} 0 0
   [junit4]   2> 1489828 T3751 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[103 
(1480910358612803584)]} 0 0
   [junit4]   2> 1489832 T3763 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[103 
(1480910358616997888)]} 0 0
   [junit4]   2> 1489837 T3750 C1789 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[104 
(1480910358622240768)]} 0 0
   [junit4]   2> 1489841 T3764 C1790 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[104 
(1480910358626435072)]} 0 0
   [junit4]   2> ASYNC  NEW_CORE C1791 name=collection1 
org.apache.solr.core.SolrCore@1aa2d04
   [junit4]   2> 1489863 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[105 
(1480910358649503744)]} 0 0
   [junit4]   2> ASYNC  NEW_CORE C1792 name=collection1 
org.apache.solr.core.SolrCore@1a582d1
   [junit4]   2> 1489867 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[105 
(1480910358653698048)]} 0 0
   [junit4]   2> 1489871 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[106 
(1480910358657892352)]} 0 0
   [junit4]   2> 1489875 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[106 
(1480910358662086656)]} 0 0
   [junit4]   2> 1489879 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[107 
(1480910358666280960)]} 0 1
   [junit4]   2> 1489883 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[107 
(1480910358669426688)]} 0 1
   [junit4]   2> 1489886 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[108 
(1480910358673620992)]} 0 0
   [junit4]   2> 1489890 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[108 
(1480910358677815296)]} 0 0
   [junit4]   2> 1489894 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[109 
(1480910358682009600)]} 0 0
   [junit4]   2> 1489898 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[109 
(1480910358685155328)]} 0 1
   [junit4]   2> 1489901 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[110 
(1480910358689349632)]} 0 0
   [junit4]   2> 1489905 T3762 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[110 
(1480910358693543936)]} 0 0
   [junit4]   2> 1489909 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[111 
(1480910358696689664)]} 0 1
   [junit4]   2> 1489912 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[111 
(1480910358700883968)]} 0 0
   [junit4]   2> 1489916 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[112 
(1480910358705078272)]} 0 0
   [junit4]   2> 1489920 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[112 
(1480910358709272576)]} 0 0
   [junit4]   2> 1489924 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[113 
(1480910358712418304)]} 0 1
   [junit4]   2> 1489927 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[113 
(1480910358716612608)]} 0 0
   [junit4]   2> 1489931 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[114 
(1480910358720806912)]} 0 0
   [junit4]   2> 1489935 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[114 
(1480910358725001216)]} 0 0
   [junit4]   2> 1489939 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[115 
(1480910358728146944)]} 0 1
   [junit4]   2> 1489943 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[115 
(1480910358732341248)]} 0 1
   [junit4]   2> 1489946 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[116 
(1480910358736535552)]} 0 0
   [junit4]   2> 1489950 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[116 
(1480910358740729856)]} 0 0
   [junit4]   2> 1489954 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[117 
(1480910358744924160)]} 0 0
   [junit4]   2> 1489958 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[117 
(1480910358749118464)]} 0 1
   [junit4]   2> 1489962 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[118 
(1480910358753312768)]} 0 1
   [junit4]   2> 1489966 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[118 
(1480910358757507072)]} 0 0
   [junit4]   2> 1489970 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[119 
(1480910358760652800)]} 0 1
   [junit4]   2> 1489974 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[119 
(1480910358764847104)]} 0 1
   [junit4]   2> 1489978 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[120 
(1480910358770089984)]} 0 1
   [junit4]   2> 1489982 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[120 
(1480910358774284288)]} 0 0
   [junit4]   2> 1489987 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[121 
(1480910358778478592)]} 0 1
   [junit4]   2> 1489991 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[121 
(1480910358782672896)]} 0 1
   [junit4]   2> 1489995 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[122 
(1480910358787915776)]} 0 0
   [junit4]   2> 1489999 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[122 
(1480910358792110080)]} 0 0
   [junit4]   2> 1490004 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[123 
(1480910358796304384)]} 0 1
   [junit4]   2> 1490008 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[123 
(1480910358800498688)]} 0 1
   [junit4]   2> 1490012 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[124 
(1480910358805741568)]} 0 0
   [junit4]   2> 1490017 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[124 
(1480910358809935872)]} 0 1
   [junit4]   2> 1490023 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[125 
(1480910358817275904)]} 0 0
   [junit4]   2> 1490030 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[125 
(1480910358824615936)]} 0 0
   [junit4]   2> 1490036 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[126 
(1480910358830907392)]} 0 0
   [junit4]   2> 1490040 T3762 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[126 
(1480910358835101696)]} 0 0
   [junit4]   2> 1490044 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[127 
(1480910358839296000)]} 0 0
   [junit4]   2> 1490048 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[127 
(1480910358842441728)]} 0 1
   [junit4]   2> 1490051 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[128 
(1480910358846636032)]} 0 0
   [junit4]   2> 1490056 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[128 
(1480910358850830336)]} 0 0
   [junit4]   2> 1490060 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[129 
(1480910358855024640)]} 0 1
   [junit4]   2> 1490064 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[129 
(1480910358860267520)]} 0 1
   [junit4]   2> 1490068 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[130 
(1480910358864461824)]} 0 1
   [junit4]   2> 1490072 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[130 
(1480910358868656128)]} 0 1
   [junit4]   2> 1490076 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[131 
(1480910358872850432)]} 0 0
   [junit4]   2> 1490080 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[131 
(1480910358877044736)]} 0 0
   [junit4]   2> 1490084 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[132 
(1480910358881239040)]} 0 0
   [junit4]   2> 1490088 T3762 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[132 
(1480910358885433344)]} 0 0
   [junit4]   2> 1490092 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[133 
(1480910358889627648)]} 0 0
   [junit4]   2> 1490096 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[133 
(1480910358893821952)]} 0 0
   [junit4]   2> 1490100 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[134 
(1480910358898016256)]} 0 0
   [junit4]   2> 1490104 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[134 
(1480910358902210560)]} 0 0
   [junit4]   2> 1490108 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[135 
(1480910358906404864)]} 0 0
   [junit4]   2> 1490112 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[135 
(1480910358910599168)]} 0 0
   [junit4]   2> 1490116 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[136 
(1480910358914793472)]} 0 0
   [junit4]   2> 1490121 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[136 
(1480910358918987776)]} 0 1
   [junit4]   2> 1490125 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[137 
(1480910358923182080)]} 0 1
   [junit4]   2> 1490129 T3762 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[137 
(1480910358927376384)]} 0 1
   [junit4]   2> 1490133 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[138 
(1480910358931570688)]} 0 1
   [junit4]   2> 1490137 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[138 
(1480910358935764992)]} 0 1
   [junit4]   2> 1490141 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[139 
(1480910358939959296)]} 0 1
   [junit4]   2> 1490145 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[139 
(1480910358944153600)]} 0 1
   [junit4]   2> 1490148 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[140 
(1480910358948347904)]} 0 0
   [junit4]   2> 1490153 T3763 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[140 
(1480910358952542208)]} 0 1
   [junit4]   2> 1490157 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[141 
(1480910358957785088)]} 0 0
   [junit4]   2> 1490162 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[141 
(1480910358961979392)]} 0 1
   [junit4]   2> 1490166 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[142 
(1480910358967222272)]} 0 0
   [junit4]   2> 1490170 T3764 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[142 
(1480910358971416576)]} 0 0
   [junit4]   2> 1490174 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[143 
(1480910358975610880)]} 0 0
   [junit4]   2> 1490179 T3762 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[143 
(1480910358979805184)]} 0 1
   [junit4]   2> 1490183 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[144 
(1480910358983999488)]} 0 1
   [junit4]   2> 1490187 T3762 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[144 
(1480910358989242368)]} 0 1
   [junit4]   2> 1490191 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[145 
(1480910358993436672)]} 0 0
   [junit4]   2> 1490196 T3762 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[145 
(1480910358998679552)]} 0 0
   [junit4]   2> 1490201 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[146 
(1480910359002873856)]} 0 1
   [junit4]   2> 1490205 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[146 
(1480910359007068160)]} 0 1
   [junit4]   2> 1490209 T3750 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[147 
(1480910359012311040)]} 0 0
   [junit4]   2> 1490213 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[147 
(1480910359016505344)]} 0 0
   [junit4]   2> 1490218 T3753 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[148 
(1480910359021748224)]} 0 1
   [junit4]   2> 1490222 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[148 
(1480910359024893952)]} 0 1
   [junit4]   2> 1490226 T3752 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[149 
(1480910359030136832)]} 0 0
   [junit4]   2> 1490231 T3765 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update params={version=2&wt=javabin} {add=[149 
(1480910359034331136)]} 0 1
   [junit4]   2> 1490234 T3751 C1791 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1490278 T3751 C1791 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-1a4e0b1))),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-1a4e0b1))),segFN=segments_3,generation=3}
   [junit4]   2> 1490279 T3751 C1791 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 3
   [junit4]   2> 1490285 T3751 C1791 oass.SolrIndexSearcher.<init> Opening 
Searcher@12da7a5[collection1] main
   [junit4]   2> 1490286 T3751 C1791 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1490288 T3755 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@12da7a5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c9)
 Uninverting(_1(5.0.0):c59)))}
   [junit4]   2> 1490289 T3751 C1791 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update 
params={version=2&waitSearcher=true&softCommit=false&wt=javabin&commit=true} 
{commit=} 0 55
   [junit4]   2> 1490292 T3762 C1792 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1490318 T3762 C1792 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-be4e84))),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-be4e84))),segFN=segments_3,generation=3}
   [junit4]   2> 1490319 T3762 C1792 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 3
   [junit4]   2> 1490324 T3762 C1792 oass.SolrIndexSearcher.<init> Opening 
Searcher@430e77[collection1] main
   [junit4]   2> 1490325 T3762 C1792 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1490328 T3767 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@430e77[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):c9)
 Uninverting(_1(5.0.0):c59)))}
   [junit4]   2> 1490329 T3762 C1792 oasup.LogUpdateProcessor.finish 
[collection1] webapp= path=/update 
params={version=2&waitSearcher=true&softCommit=false&wt=javabin&commit=true} 
{commit=} 0 37
   [junit4]   2> 1490333 T3750 C1791 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_ti1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490454 T3762 C1792 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::114]:33332/|https://127.0.0.1:33748/|[ff01::083]:33332/|[ff01::213]:33332/&fl=id&rows=10&start=0&fsv=true&sort=n_ti1+desc&wt=javabin&NOW=1412306174419&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=1 
   [junit4]   2> 1490458 T3762 C1792 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=7,146,12,149,11,102,143,115,105,127&version=2&shard.url=[ff01::114]:33332/|https://127.0.0.1:33748/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&NOW=1412306174419&distrib=false&isShard=true&q=*:*}
 status=0 QTime=1 
   [junit4]   2> 1490460 T3765 C1792 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::114]:33332/|https://127.0.0.1:33748/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=122 
   [junit4]   2> 1490465 T3752 C1791 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_ti1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C1793 name=collection1 
org.apache.solr.core.SolrCore@1a582d1
   [junit4]   2> 1490497 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_ti1+asc&wt=javabin&NOW=1412306174550&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490502 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=148,121,108,8,145,113,2,112,1,142&version=2&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&wt=javabin&NOW=1412306174550&distrib=false&isShard=true&q=*:*}
 status=0 QTime=1 
   [junit4]   2> 1490504 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&sort=n_ti1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=35 
   [junit4]   2> ASYNC  NEW_CORE C1794 name=collection1 
org.apache.solr.core.SolrCore@1aa2d04
   [junit4]   2> 1490510 T3751 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_f1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=2 
   [junit4]   2> 1490519 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_f1+desc&wt=javabin&NOW=1412306174595&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=2 
   [junit4]   2> 1490523 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=13,101,11,147,138,112,105,124,5,116&version=2&shard.url=https://127.0.0.1:33748/&wt=javabin&NOW=1412306174595&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490525 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/&sort=n_f1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=11 
   [junit4]   2> 1490529 T3753 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_f1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490537 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::213]:33332/|https://127.0.0.1:33748/|[ff01::114]:33332/&fl=id&rows=10&start=0&fsv=true&sort=n_f1+asc&wt=javabin&NOW=1412306174615&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490541 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=7,121,9,104,130,2,14,135,1,131&version=2&shard.url=[ff01::213]:33332/|https://127.0.0.1:33748/|[ff01::114]:33332/&wt=javabin&NOW=1412306174615&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490543 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::213]:33332/|https://127.0.0.1:33748/|[ff01::114]:33332/&sort=n_f1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=9 
   [junit4]   2> 1490547 T3751 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_tf1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490555 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/|[ff01::213]:33332/|[ff01::114]:33332/&fl=id&rows=10&start=0&fsv=true&sort=n_tf1+desc&wt=javabin&NOW=1412306174632&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=1 
   [junit4]   2> 1490559 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=110,143,130,123,115,100,1001,111,134,142&version=2&shard.url=https://127.0.0.1:33748/|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&NOW=1412306174632&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490561 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_tf1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=10 
   [junit4]   2> 1490566 T3750 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_tf1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=1 
   [junit4]   2> 1490573 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::213]:33332/|https://127.0.0.1:33748/|[ff01::114]:33332/&fl=id&rows=10&start=0&fsv=true&sort=n_tf1+asc&wt=javabin&NOW=1412306174651&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490577 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=148,137,12,128,104,14,135,127,133,131&version=2&shard.url=[ff01::213]:33332/|https://127.0.0.1:33748/|[ff01::114]:33332/&wt=javabin&NOW=1412306174651&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490579 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::213]:33332/|https://127.0.0.1:33748/|[ff01::114]:33332/&sort=n_tf1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=9 
   [junit4]   2> 1490583 T3752 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_d1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=1 
   [junit4]   2> 1490590 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_d1+desc&wt=javabin&NOW=1412306174668&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490594 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=109,12,121,3,14,126,105,118,142,116&version=2&shard.url=https://127.0.0.1:33748/&wt=javabin&NOW=1412306174668&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490596 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/&sort=n_d1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=9 
   [junit4]   2> 1490600 T3751 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_d1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490607 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_d1+asc&wt=javabin&NOW=1412306174685&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490611 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=146,137,149,8,143,115,2,138,5,134&version=2&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&wt=javabin&NOW=1412306174685&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490612 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&sort=n_d1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=8 
   [junit4]   2> 1490624 T3750 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_td1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=8 
   [junit4]   2> 1490640 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_td1+desc&wt=javabin&NOW=1412306174709&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=8 
   [junit4]   2> 1490644 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=110,143,125,123,144,114,113,112,105,141&version=2&shard.url=https://127.0.0.1:33748/&wt=javabin&NOW=1412306174709&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490646 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/&sort=n_td1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=18 
   [junit4]   2> 1490650 T3750 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_td1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=1 
   [junit4]   2> 1490656 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/|[ff01::083]:33332/|[ff01::213]:33332/&fl=id&rows=10&start=0&fsv=true&sort=n_td1+asc&wt=javabin&NOW=1412306174734&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490660 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=6,108,8,102,130,139,132,129,122,142&version=2&shard.url=https://127.0.0.1:33748/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&NOW=1412306174734&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490662 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_td1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=9 
   [junit4]   2> 1490666 T3752 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_l1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490673 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/|[ff01::083]:33332/&fl=id&rows=10&start=0&fsv=true&sort=n_l1+desc&wt=javabin&NOW=1412306174751&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=1 
   [junit4]   2> 1490677 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=148,109,110,149,108,144,136,115,112,100&version=2&shard.url=https://127.0.0.1:33748/|[ff01::083]:33332/&wt=javabin&NOW=1412306174751&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490678 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/|[ff01::083]:33332/&sort=n_l1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=8 
   [junit4]   2> 1490682 T3752 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_l1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490688 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_l1+asc&wt=javabin&NOW=1412306174766&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490692 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=7,6,3,139,147,126,122,5,142,131&version=2&shard.url=https://127.0.0.1:33748/&wt=javabin&NOW=1412306174766&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490693 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/&sort=n_l1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=8 
   [junit4]   2> 1490707 T3751 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_tl1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=10 
   [junit4]   2> 1490724 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_tl1+desc&wt=javabin&NOW=1412306174792&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=10 
   [junit4]   2> 1490728 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=110,7,123,135,112,147,103,1001,5,107&version=2&shard.url=https://127.0.0.1:33748/&wt=javabin&NOW=1412306174792&distrib=false&isShard=true&q=*:*}
 status=0 QTime=1 
   [junit4]   2> 1490729 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/&sort=n_tl1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=18 
   [junit4]   2> 1490733 T3750 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_tl1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490740 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::083]:33332/|https://127.0.0.1:33748/|[ff01::213]:33332/&fl=id&rows=10&start=0&fsv=true&sort=n_tl1+asc&wt=javabin&NOW=1412306174818&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490744 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=109,16,11,12,10,3,100,122,142,127&version=2&shard.url=[ff01::083]:33332/|https://127.0.0.1:33748/|[ff01::213]:33332/&wt=javabin&NOW=1412306174818&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490745 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::083]:33332/|https://127.0.0.1:33748/|[ff01::213]:33332/&sort=n_tl1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=8 
   [junit4]   2> 1490751 T3750 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_dt1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=2 
   [junit4]   2> 1490760 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::083]:33332/|https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_dt1+desc&wt=javabin&NOW=1412306174836&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=2 
   [junit4]   2> 1490764 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=17,137,12,10,136,14,2,126,129,131&version=2&shard.url=[ff01::083]:33332/|https://127.0.0.1:33748/&wt=javabin&NOW=1412306174836&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490765 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::083]:33332/|https://127.0.0.1:33748/&sort=n_dt1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=10 
   [junit4]   2> 1490769 T3750 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_dt1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490775 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_dt1+asc&wt=javabin&NOW=1412306174853&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490780 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=146,140,108,144,123,125,100,1001,111,118&version=2&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:33748/&wt=javabin&NOW=1412306174853&distrib=false&isShard=true&q=*:*}
 status=0 QTime=1 
   [junit4]   2> 1490781 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:33748/&sort=n_dt1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=9 
   [junit4]   2> 1490806 T3752 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_tdt1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=19 
   [junit4]   2> 1490825 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::213]:33332/|https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_tdt1+desc&wt=javabin&NOW=1412306174891&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=11 
   [junit4]   2> 1490830 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=8,143,115,113,120,105,122,1,107,133&version=2&shard.url=[ff01::213]:33332/|https://127.0.0.1:33748/&wt=javabin&NOW=1412306174891&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490831 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::213]:33332/|https://127.0.0.1:33748/&sort=n_tdt1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=21 
   [junit4]   2> 1490835 T3751 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=n_tdt1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490842 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::213]:33332/|https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=n_tdt1+asc&wt=javabin&NOW=1412306174920&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490846 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=13,11,121,15,145,144,2,138,111,117&version=2&shard.url=[ff01::213]:33332/|https://127.0.0.1:33748/&wt=javabin&NOW=1412306174920&distrib=false&isShard=true&q=*:*}
 status=0 QTime=1 
   [junit4]   2> 1490847 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::213]:33332/|https://127.0.0.1:33748/&sort=n_tdt1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=8 
   [junit4]   2> 1490851 T3750 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=a_i1+desc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=1 
   [junit4]   2> 1490858 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=a_i1+desc&wt=javabin&NOW=1412306174935&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490862 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=7,13,12,10,9,8,3,2,5,1&version=2&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|https://127.0.0.1:33748/&wt=javabin&NOW=1412306174935&distrib=false&isShard=true&q=*:*}
 status=0 QTime=1 
   [junit4]   2> 1490863 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|https://127.0.0.1:33748/&sort=a_i1+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=9 
   [junit4]   2> 1490867 T3753 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&distrib=false&q=*:*}
 hits=68 status=0 QTime=1 
   [junit4]   2> 1490874 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&NOW=1412306174952&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=1 
   [junit4]   2> 1490878 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=7,13,12,10,9,8,3,2,5,1&version=2&shard.url=https://127.0.0.1:33748/&wt=javabin&NOW=1412306174952&distrib=false&isShard=true&q=*:*}
 status=0 QTime=1 
   [junit4]   2> 1490879 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=https://127.0.0.1:33748/&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=8 
   [junit4]   2> 1490882 T3752 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&sort=a_i1+asc&wt=javabin&distrib=false&q=*:*} hits=68 
status=0 QTime=0 
   [junit4]   2> 1490889 T3762 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&fl=id&rows=10&start=0&fsv=true&sort=a_i1+asc&wt=javabin&NOW=1412306174967&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=0 
   [junit4]   2> 1490893 T3763 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=7,13,11,6,12,9,3,2,4,1&version=2&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&wt=javabin&NOW=1412306174967&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490894 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shards=[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:33748/&sort=a_i1+asc&wt=javabin&q=*:*}
 hits=68 status=0 QTime=8 
   [junit4]   2> 1490898 T3751 C1794 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&fl=*,score&sort=a_i1+desc&wt=javabin&distrib=false&q=*:*} 
hits=68 status=0 QTime=0 
   [junit4]   2> 1490905 T3765 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={version=2&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:33748/|[ff01::213]:33332/&fl=id,score&rows=10&start=0&fsv=true&sort=a_i1+desc&wt=javabin&NOW=1412306174983&distrib=false&isShard=true&q=*:*}
 hits=68 status=0 QTime=1 
   [junit4]   2> 1490908 T3764 C1793 oasc.SolrCore.execute [collection1] 
webapp= path=/select 
params={ids=7,13,12,10,9,8,3,2,5,1&version=2&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:33748/|[ff01::213]:33332/&fl=*,score&wt=javabin&NOW=1412306174983&distrib=false&isShard=true&q=*:*}
 status=0 QTime=0 
   [junit4]   2> 1490910 T3763 C1793 oasc.SolrCore.execute [collection1] w

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

nit4]   2> 1517595 T3744 oasc.CachingDirectoryFactory.closeCacheValue looking 
to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard0/data
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard0/data;done=false>>]
   [junit4]   2> 1517595 T3744 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard0/data
   [junit4]   2> 1517598 T3744 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/,null}
   [junit4]   2> 1522594 T3744 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=25962012
   [junit4]   2> 1522596 T3744 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@17a287d
   [junit4]   2> 1522632 T3744 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=8907,transaction_logs_total_number=3}
   [junit4]   2> 1522633 T3744 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1522633 T3744 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1522633 T3744 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1522634 T3744 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1522634 T3744 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1522635 T3744 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard1/data/index
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard1/data/index;done=false>>]
   [junit4]   2> 1522635 T3744 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard1/data/index
   [junit4]   2> 1522635 T3744 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard1/data
 
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard1/data;done=false>>]
   [junit4]   2> 1522636 T3744 oasc.CachingDirectoryFactory.close Closing 
directory: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001/tempDir-001/shard1/data
   [junit4]   2> 1522639 T3744 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/,null}
   [junit4]   2> 1527595 T3744 oas.SolrTestCaseJ4.tearDown ###Ending 
testDistribSearch
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=testDistribSearch 
-Dtests.seed=377AFD4F005F159A -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=ar_KW -Dtests.timezone=Asia/Dushanbe -Dtests.file.encoding=UTF-8
   [junit4] ERROR   46.6s J0 | 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([377AFD4F005F159A:B69C7357770075A6]: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> 1527613 T3744 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch-377AFD4F005F159A-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): 
{multiDefault=FST41, lowerfilt1and2=FST41, a_n_tdt=PostingsFormat(name=Memory 
doPackFST= false), other_tl1=FST41, timestamp=FST41, 
foo_d=PostingsFormat(name=MockRandom), a_t=FST41, 
_version_=PostingsFormat(name=SimpleText), 
oddField_s=PostingsFormat(name=MockRandom), 
n_tdt1=PostingsFormat(name=SimpleText), id=PostingsFormat(name=MockRandom), 
foo_f=PostingsFormat(name=Memory doPackFST= false), 
n_dt1=PostingsFormat(name=SimpleText), foo_b=PostingsFormat(name=Memory 
doPackFST= false), n_f1=PostingsFormat(name=SimpleText), intDefault=FST41, 
n_d1=FST41, SubjectTerms_mfacet=PostingsFormat(name=SimpleText), 
n_tl1=PostingsFormat(name=SimpleText), rnd_b=PostingsFormat(name=MockRandom), 
n_td1=PostingsFormat(name=SimpleText), n_l1=FST41, 
text=PostingsFormat(name=Memory doPackFST= false), a_i1=FST41, n_tf1=FST41, 
range_facet_l=FST41, lowerfilt=FST41, b_n_tdt=FST41, 
n_ti1=PostingsFormat(name=MockRandom)}, 
docValues:{timestamp=DocValuesFormat(name=Lucene410)}, 
sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=ar_KW, 
timezone=Asia/Dushanbe
   [junit4]   2> NOTE: Linux 3.13.0-36-generic i386/Oracle Corporation 1.7.0_67 
(32-bit)/cpus=8,threads=1,free=140623560,total=300724224
   [junit4]   2> NOTE: All tests run in this JVM: [QueryEqualityTest, 
NotRequiredUniqueKeyTest, NoCacheHeaderTest, 
DistributedSpellCheckComponentTest, DistributedExpandComponentTest, 
TestBinaryResponseWriter, ZkNodePropsTest, SignatureUpdateProcessorFactoryTest, 
HdfsLockFactoryTest, HdfsBasicDistributedZkTest, TestAnalyzedSuggestions, 
TestJmxIntegration, SimpleFacetsTest, BasicDistributedZkTest, 
DistributedFacetPivotSmallTest, TestFastLRUCache, SuggesterTest, 
CoreAdminRequestStatusTest, XmlUpdateRequestHandlerTest, 
RegexBoostProcessorTest, EchoParamsTest, ShowFileRequestHandlerTest, 
PeerSyncTest, TestManagedSchemaFieldResource, 
DeleteLastCustomShardedReplicaTest, TestDocSet, DisMaxRequestHandlerTest, 
StatelessScriptUpdateProcessorFactoryTest, SuggesterTSTTest, 
TestPivotHelperCode, TestOverriddenPrefixQueryForCustomFieldType, 
CoreAdminCreateDiscoverTest, SpellCheckCollatorTest, FastVectorHighlighterTest, 
TestCloudManagedSchemaConcurrent, TestArbitraryIndexDir, TestSchemaResource, 
TestCloudManagedSchema, TestHashPartitioner, TestConfigSets, SyncSliceTest, 
TestQuerySenderListener, BasicFunctionalityTest, StandardRequestHandlerTest, 
BJQParserTest, TestModifyConfFiles, HdfsChaosMonkeySafeLeaderTest, 
SpellCheckComponentTest, ExternalFileFieldSortTest, TestRecoveryHdfs, 
LeaderFailoverAfterPartitionTest, TestMiniSolrCloudCluster, TestCodecSupport, 
TestLeaderElectionZkExpiry, TestAnalyzeInfixSuggestions, 
PingRequestHandlerTest, DOMUtilTest, TestStressVersions, 
TestDistributedMissingSort, RemoteQueryErrorTest, TestSolrJ, TestSolr4Spatial2, 
CoreAdminHandlerTest, HttpPartitionTest, TestSuggestSpellingConverter, 
BlockCacheTest, SolrIndexSplitterTest, TestFoldingMultitermQuery, 
CachingDirectoryFactoryTest, PluginInfoTest, SpellPossibilityIteratorTest, 
PrimitiveFieldTypeTest, FileUtilsTest, DistributedFacetPivotLongTailTest, 
ReturnFieldsTest, ShardRoutingTest, TimeZoneUtilsTest, 
OverseerCollectionProcessorTest, TestDistributedSearch]
   [junit4] Completed on J0 in 46.59s, 1 test, 1 error <<< FAILURES!

[...truncated 794 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:524: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:472: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following 
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:189: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:496: The 
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1360: 
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:962: 
There were test failures: 432 suites, 1779 tests, 1 error, 44 ignored (22 
assumptions)

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


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

Reply via email to