[JENKINS] Lucene-Solr-5.x-MacOSX (64bit/jdk1.7.0) - Build # 1813 - Failure!

[Policeman Jenkins Server]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/1813/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC

1 tests failed.
REGRESSION:  org.apache.solr.TestDistributedGrouping.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([71C929CD17DE5EBF:F02FA7D560813E83]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:568)
        at 
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
        at 
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
        at 
org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
        at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
        at 
org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:512)
        at 
org.apache.solr.TestDistributedGrouping.simpleQuery(TestDistributedGrouping.java:274)
        at 
org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:262)
        at 
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:875)
        at sun.reflect.GeneratedMethodAccessor39.invoke(Unknown Source)
        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 11163 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedGrouping
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/init-core-data-001
   [junit4]   2> 2149340 T4867 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl 
(false) and clientAuth (false)
   [junit4]   2> 2149341 T4867 
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system 
property: /fe_/
   [junit4]   2> 2149346 T4867 oas.SolrTestCaseJ4.setUp ###Starting 
testDistribSearch
   [junit4]   2> 2149348 T4867 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2149356 T4867 oejs.AbstractConnector.doStart Started 
SelectChannelConnector@127.0.0.1:54614
   [junit4]   2> 2149356 T4867 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 2149357 T4867 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 2149357 T4867 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr
   [junit4]   2> 2149357 T4867 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/'
   [junit4]   2> 2149408 T4867 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/solr.xml
   [junit4]   2> 2149559 T4867 oasc.CoreContainer.<init> New CoreContainer 
1130223548
   [junit4]   2> 2149560 T4867 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/]
   [junit4]   2> 2149563 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 2149563 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 2149564 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 2149564 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2149564 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 2149565 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2149565 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 2149566 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 2149566 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 2149567 T4867 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 2149568 T4867 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2149569 T4867 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2149573 T4867 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2149589 T4877 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 2149592 T4877 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 2149592 T4877 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 2149593 T4877 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 2149681 T4877 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 2149771 T4877 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 2149773 T4877 oass.IndexSchema.readSchema Reading Solr Schema 
from schema.xml
   [junit4]   2> 2149798 T4877 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 2150294 T4877 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2150311 T4877 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 2150316 T4877 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2150331 T4877 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 2150338 T4877 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 2150344 T4877 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2150345 T4877 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 2150346 T4877 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 2150347 T4877 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2150347 T4877 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 2150347 T4877 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 2150348 T4877 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 2150348 T4877 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2150348 T4877 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/,
 
dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/control/data/
   [junit4]   2> 2150349 T4877 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1189fa63
   [junit4]   2> 2150349 T4877 oasc.CachingDirectoryFactory.get return new 
directory for 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/control/data
   [junit4]   2> 2150349 T4877 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/control/data/index/
   [junit4]   2> 2150350 T4877 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/control/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2150350 T4877 oasc.CachingDirectoryFactory.get return new 
directory for 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/control/data/index
   [junit4]   2> 2150351 T4877 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.22104642408752745]
   [junit4]   2> 2150352 T4877 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-1b07e872))),segFN=segments_1,generation=1}
   [junit4]   2> 2150352 T4877 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 2150352 T4877 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 2150362 T4877 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 2150362 T4877 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2150362 T4877 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2150363 T4877 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 2150363 T4877 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2150363 T4877 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2150363 T4877 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2150363 T4877 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2150364 T4877 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2150364 T4877 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2150364 T4877 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 2150365 T4877 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 2150365 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 2150366 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 2150366 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 2150369 T4877 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 2150369 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 2150370 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 2150371 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
   [junit4]   2> 2150372 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created dismax: solr.SearchHandler
   [junit4]   2> 2150374 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 2150374 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 2150375 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 2150377 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 2150378 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 2150378 T4877 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 2150378 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 2150379 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 2150380 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2150380 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2150381 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2150381 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2150382 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak_Direct: 
org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2150382 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2150383 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2150383 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2150384 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 2150384 T4877 oasc.RequestHandlers.initHandlersFromConfig 
created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 2150398 T4877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2150400 T4877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2150402 T4877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2150405 T4877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2150407 T4877 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 2150411 T4877 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 2150412 T4877 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 2150412 T4877 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=30, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=55.802734375, 
floorSegmentMB=1.4345703125, forceMergeDeletesPctAllowed=28.264994291919685, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8296484276631253
   [junit4]   2> 2150413 T4877 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-1b07e872))),segFN=segments_1,generation=1}
   [junit4]   2> 2150413 T4877 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 2150413 T4877 oass.SolrIndexSearcher.<init> Opening 
Searcher@421fc7fa[collection1] main
   [junit4]   2> 2150414 T4877 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 2150414 T4877 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 2150414 T4877 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 2150414 T4877 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 2150415 T4877 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 2150415 T4877 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 2150427 T4877 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2150493 T4878 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@421fc7fa[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2150501 T4877 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 2150503 T4867 oass.SolrDispatchFilter.init 
user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
   [junit4]   2> 2150503 T4867 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 2150505 T4867 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 2150511 T4867 oejs.AbstractConnector.doStart Started 
SelectChannelConnector@127.0.0.1:54615
   [junit4]   2> 2150512 T4867 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 2150513 T4867 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 2150513 T4867 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr
   [junit4]   2> 2150513 T4867 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/'
   [junit4]   2> 2150576 T4867 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/solr.xml
   [junit4]   2> 2150653 T4867 oasc.CoreContainer.<init> New CoreContainer 
340436725
   [junit4]   2> 2150653 T4867 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/]
   [junit4]   2> 2150654 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 2150655 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 2150655 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 2150655 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 2150656 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 2150656 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 2150656 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 2150657 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 2150658 T4867 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 2150658 T4867 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 2150659 T4867 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2150659 T4867 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2150659 T4867 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 2150665 T4889 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 2150668 T4889 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 2150669 T4889 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 2150669 T4889 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 2150767 T4889 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 2150846 T4889 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 2150848 T4889 oass.IndexSchema.readSchema Reading Solr Schema 
from schema.xml
   [junit4]   2> 2150863 T4889 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 2151313 T4889 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2151327 T4889 oass.IndexSchema.readSchema default search field 
in schema is text
   [junit4]   2> 2151331 T4889 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 2151343 T4889 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 2151348 T4889 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
   [junit4]   2> 2151354 T4889 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2151355 T4889 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 2151357 T4889 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 2151358 T4889 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 2151358 T4889 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
   [junit4]   2> 2151358 T4889 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
   [junit4]   2> 2151359 T4889 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 2151359 T4889 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2151359 T4889 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/,
 
dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard0/data/
   [junit4]   2> 2151360 T4889 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1189fa63
   [junit4]   2> 2151361 T4889 oasc.CachingDirectoryFactory.get return new 
directory for 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard0/data
   [junit4]   2> 2151361 T4889 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard0/data/index/
   [junit4]   2> 2151362 T4889 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard0/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2151362 T4889 oasc.CachingDirectoryFactory.get return new 
directory for 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard0/data/index
   [junit4]   2> 2151362 T4889 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.22104642408752745]
   [junit4]   2> 2151364 T4889 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-37dac1b4))),segFN=segments_1,generation=1}
   [junit4]   2> 2151364 T4889 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 2151364 T4889 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
   [junit4]   2> 2151372 T4889 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 2151372 T4889 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2151373 T4889 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2151373 T4889 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 2151375 T4889 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2151375 T4889 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2151375 T4889 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2151376 T4889 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2151376 T4889 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2151378 T4889 oasup.UpdateRequestProcessorChain.init creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2151378 T4889 oasup.UpdateRequestProcessorChain.init inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 2151378 T4889 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 2151379 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 2151379 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 2151380 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 2151380 T4889 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 2151381 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 2151381 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 2151382 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
   [junit4]   2> 2151383 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created dismax: solr.SearchHandler
   [junit4]   2> 2151384 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
   [junit4]   2> 2151385 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 2151385 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 2151386 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/fileedit: solr.admin.EditFileRequestHandler
   [junit4]   2> 2151387 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 2151387 T4889 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 2151387 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 2151388 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 2151388 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /terms: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2151389 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2151390 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2151391 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2151391 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak_Direct: 
org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2151392 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2151392 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created mltrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2151393 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created tvrh: org.apache.solr.handler.component.SearchHandler
   [junit4]   2> 2151395 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /mlt: solr.MoreLikeThisHandler
   [junit4]   2> 2151395 T4889 oasc.RequestHandlers.initHandlersFromConfig 
created /debug/dump: solr.DumpRequestHandler
   [junit4]   2> 2151411 T4889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2151414 T4889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2151416 T4889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2151420 T4889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 2151421 T4889 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
   [junit4]   2> 2151425 T4889 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 2151425 T4889 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 2151426 T4889 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=30, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=55.802734375, 
floorSegmentMB=1.4345703125, forceMergeDeletesPctAllowed=28.264994291919685, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8296484276631253
   [junit4]   2> 2151426 T4889 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory(id=lucene-37dac1b4))),segFN=segments_1,generation=1}
   [junit4]   2> 2151427 T4889 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 2151427 T4889 oass.SolrIndexSearcher.<init> Opening 
Searcher@1c263a8c[collection1] main
   [junit4]   2> 2151427 T4889 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 2151428 T4889 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
   [junit4]   2> 2151429 T4889 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 2151429 T4889 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using InMemoryStorage
   [junit4]   2> 2151429 T4889 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 2151429 T4889 oashc.SpellCheckComponent.inform Initializing 
spell checkers
   [junit4]   2> 2151438 T4889 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2151491 T4890 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1c263a8c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2151505 T4889 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 2151506 T4867 oass.SolrDispatchFilter.init 
user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
   [junit4]   2> 2151506 T4867 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C738 name=collection1 
org.apache.solr.core.SolrCore@12aba1af
   [junit4]   2> 2151516 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} 
{deleteByQuery=*:* (-1480362636474318848)} 0 5
   [junit4]   2> ASYNC  NEW_CORE C739 name=collection1 
org.apache.solr.core.SolrCore@1c5c4951
   [junit4]   2> 2151524 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} 
{deleteByQuery=*:* (-1480362636483756032)} 0 4
   [junit4]   2> 2151533 T4871 C738 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2151534 T4871 C738 oasu.DirectUpdateHandler2.commit No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2151534 T4871 C738 oasc.SolrCore.openNewSearcher 
SolrIndexSearcher has not changed - not re-opening: 
org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 2151534 T4871 C738 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 2151534 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update 
params={wt=javabin&softCommit=false&waitSearcher=true&version=2&commit=true} 
{commit=} 0 1
   [junit4]   2> 2151540 T4886 C739 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2151540 T4886 C739 oasu.DirectUpdateHandler2.commit No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2151541 T4886 C739 oasc.SolrCore.openNewSearcher 
SolrIndexSearcher has not changed - not re-opening: 
org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 2151542 T4886 C739 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 2151543 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update 
params={wt=javabin&softCommit=false&waitSearcher=true&version=2&commit=true} 
{commit=} 0 3
   [junit4]   2> 2151556 T4872 C738 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&q=*:*&distrib=false&sort=a_i1+asc,+id+asc&fl=id,a_i1&group.limit=10&group.field=a_i1&rows=100&version=2}
 hits=0 status=0 QTime=2 
   [junit4]   2> 2151572 T4888 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&q=*:*&distrib=false&group.distributed.first=true&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:54615/fe_&fl=id&start=0&isShard=true&group.field=a_i1&group.limit=10&rows=100&version=2&NOW=1411783825421}
 status=0 QTime=1 
   [junit4]   2> 2151578 T4887 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&shards=http://127.0.0.1:54615/fe_&q=*:*&sort=a_i1+asc,+id+asc&fl=id,a_i1&group.limit=10&group.field=a_i1&rows=100&version=2}
 status=0 QTime=19 
   [junit4]   2> 2151583 T4873 C738 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&hl.fl=a_t&q=*:*&distrib=false&sort=a_i1+asc,+id+asc&hl=true&fl=id,a_i1&group.limit=10&group.field=a_i1&rows=100&version=2}
 hits=0 status=0 QTime=0 
   [junit4]   2> 2151597 T4884 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={wt=javabin&hl.fl=a_t&sort=a_i1+asc,+id+asc&group.distributed.first=true&distrib=false&hl=false&fl=id&isShard=true&version=2&NOW=1411783825455&group=true&q=*:*&shard.url=http://127.0.0.1:54615/fe_|[ff01::213]:33332/fe_|[ff01::114]:33332/fe_|[ff01::083]:33332/fe_&start=0&group.limit=10&group.field=a_i1&rows=100}
 status=0 QTime=0 
   [junit4]   2> 2151599 T4883 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&shards=http://127.0.0.1:54615/fe_|[ff01::213]:33332/fe_|[ff01::114]:33332/fe_|[ff01::083]:33332/fe_&hl.fl=a_t&q=*:*&sort=a_i1+asc,+id+asc&hl=true&fl=id,a_i1&group.limit=10&group.field=a_i1&rows=100&version=2}
 status=0 QTime=6 
   [junit4]   2> 2151604 T4874 C738 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={facet=true&group=true&wt=javabin&q=*:*&distrib=false&sort=a_i1+asc,+id+asc&fl=id,a_i1&facet.field=a_t&group.limit=10&group.field=a_i1&rows=100&version=2}
 hits=0 status=0 QTime=0 
   [junit4]   2> 2151612 T4886 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={facet=false&wt=javabin&sort=a_i1+asc,+id+asc&group.distributed.first=true&distrib=false&fl=id&isShard=true&facet.field=a_t&version=2&NOW=1411783825470&group=true&q=*:*&shard.url=http://127.0.0.1:54615/fe_&start=0&group.limit=10&group.field=a_i1&rows=100}
 status=0 QTime=0 
   [junit4]   2> 2151621 T4888 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={f.a_t.facet.mincount=0&facet=true&wt=javabin&distrib=false&sort=a_i1+asc,+id+asc&fl=id&isShard=true&facet.field=a_t&version=2&NOW=1411783825470&group=true&q=*:*&f.a_t.facet.limit=160&shard.url=http://127.0.0.1:54615/fe_&start=0&group.limit=10&group.field=a_i1&group.distributed.second=true&rows=100}
 status=0 QTime=1 
   [junit4]   2> 2151622 T4885 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={facet=true&group=true&wt=javabin&shards=http://127.0.0.1:54615/fe_&q=*:*&sort=a_i1+asc,+id+asc&fl=id,a_i1&facet.field=a_t&group.limit=10&group.field=a_i1&rows=100&version=2}
 status=0 QTime=14 
   [junit4]   2> 2151627 T4875 C738 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&q=*:*&distrib=false&sort=a_i1+asc,+id+asc&stats=true&fl=id,a_i1&group.limit=10&group.field=a_i1&rows=100&version=2&stats.field=a_i1}
 hits=0 status=0 QTime=1 
   [junit4]   2> 2151637 T4884 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={wt=javabin&sort=a_i1+asc,+id+asc&group.distributed.first=true&distrib=false&fl=id&isShard=true&version=2&NOW=1411783825492&group=true&q=*:*&shard.url=[ff01::114]:33332/fe_|[ff01::083]:33332/fe_|[ff01::213]:33332/fe_|http://127.0.0.1:54615/fe_&stats=false&start=0&group.limit=10&group.field=a_i1&rows=100&stats.field=a_i1}
 status=0 QTime=0 
   [junit4]   2> 2151643 T4883 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={wt=javabin&sort=a_i1+asc,+id+asc&distrib=false&fl=id&isShard=true&version=2&NOW=1411783825492&group=true&q=*:*&shard.url=[ff01::114]:33332/fe_|[ff01::083]:33332/fe_|[ff01::213]:33332/fe_|http://127.0.0.1:54615/fe_&stats=true&start=0&group.limit=10&group.field=a_i1&group.distributed.second=true&rows=100&stats.field=a_i1}
 status=0 QTime=1 
   [junit4]   2> 2151644 T4887 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&shards=[ff01::114]:33332/fe_|[ff01::083]:33332/fe_|[ff01::213]:33332/fe_|http://127.0.0.1:54615/fe_&q=*:*&sort=a_i1+asc,+id+asc&stats=true&fl=id,a_i1&group.limit=10&group.field=a_i1&rows=100&version=2&stats.field=a_i1}
 status=0 QTime=14 
   [junit4]   2> 2151674 T4876 C738 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&q=kings&distrib=false&qt=spellCheckCompRH&sort=a_i1+asc,+id+asc&spellcheck.build=true&fl=id,a_i1&spellcheck=true&group.limit=10&group.field=a_i1&rows=100&version=2}
 hits=0 status=0 QTime=25 
   [junit4]   2> 2151702 T4888 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={spellcheck.onlyMorePopular=false&wt=javabin&distrib=false&group.distributed.first=true&sort=a_i1+asc,+id+asc&fl=id&isShard=true&spellcheck.count=5&spellcheck=true&spellcheck.extendedResults=false&version=2&NOW=1411783825539&group=true&q=kings&shard.url=http://127.0.0.1:54615/fe_|[ff01::114]:33332/fe_&spellcheck.build=true&start=0&group.limit=10&group.field=a_i1&rows=100}
 status=0 QTime=1 
   [junit4]   2> 2151703 T4886 C739 oashc.SpellCheckComponent.finishStage 
http://127.0.0.1:54615/fe_|[ff01::114]:33332/fe_ null
   [junit4]   2> 2151703 T4886 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={group=true&wt=javabin&shards=http://127.0.0.1:54615/fe_|[ff01::114]:33332/fe_&q=kings&qt=spellCheckCompRH&sort=a_i1+asc,+id+asc&spellcheck.build=true&fl=id,a_i1&spellcheck=true&group.limit=10&group.field=a_i1&rows=100&version=2}
 status=0 QTime=26 
   [junit4]   2> 2151738 T4871 C738 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={facet=true&group=true&wt=javabin&q=*:*&distrib=false&group.truncate=true&sort=a_i1+asc,+id+asc&fl=id,a_i1&fq=a_s:a&facet.field=a_t&group.limit=10&group.field=a_i1&rows=100&version=2}
 hits=0 status=0 QTime=32 
   [junit4]   2> 2151752 T4883 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={facet=false&wt=javabin&distrib=false&group.distributed.first=true&group.truncate=true&sort=a_i1+asc,+id+asc&fl=id&isShard=true&facet.field=a_t&version=2&NOW=1411783825606&group=true&q=*:*&shard.url=http://127.0.0.1:54615/fe_|[ff01::213]:33332/fe_&fq=a_s:a&start=0&group.limit=10&group.field=a_i1&rows=100}
 status=0 QTime=0 
   [junit4]   2> 2151762 T4887 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={f.a_t.facet.mincount=0&facet=true&wt=javabin&distrib=false&group.truncate=true&sort=a_i1+asc,+id+asc&fl=id&isShard=true&facet.field=a_t&version=2&NOW=1411783825606&group=true&q=*:*&f.a_t.facet.limit=160&shard.url=http://127.0.0.1:54615/fe_|[ff01::213]:33332/fe_&fq=a_s:a&start=0&group.distributed.second=true&group.limit=10&group.field=a_i1&rows=100}
 status=0 QTime=1 
   [junit4]   2> 2151765 T4885 C739 oasc.SolrCore.execute [collection1] 
webapp=/fe_ path=/select 
params={facet=true&group=true&wt=javabin&shards=http://127.0.0.1:54615/fe_|[ff01::213]:33332/fe_&q=*:*&group.truncate=true&sort=a_i1+asc,+id+asc&fl=id,a_i1&fq=a_s:a&facet.field=a_t&group.limit=10&group.field=a_i1&rows=100&version=2}
 status=0 QTime=21 
   [junit4]   2> 2151773 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[1 
(1480362636744851456)]} 0 4
   [junit4]   2> 2151781 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[1 
(1480362636753240064)]} 0 4
   [junit4]   2> 2151787 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[2 
(1480362636763725824)]} 0 1
   [junit4]   2> 2151793 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[2 
(1480362636768968704)]} 0 1
   [junit4]   2> 2151802 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[3 
(1480362636778405888)]} 0 3
   [junit4]   2> 2151807 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[3 
(1480362636783648768)]} 0 1
   [junit4]   2> 2151814 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[4 
(1480362636790988800)]} 0 1
   [junit4]   2> 2151818 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[4 
(1480362636795183104)]} 0 1
   [junit4]   2> 2151825 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[5 
(1480362636802523136)]} 0 2
   [junit4]   2> 2151830 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[5 
(1480362636807766016)]} 0 1
   [junit4]   2> 2151836 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[6 
(1480362636814057472)]} 0 2
   [junit4]   2> 2151840 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[6 
(1480362636819300352)]} 0 1
   [junit4]   2> 2151844 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[7 
(1480362636822446080)]} 0 1
   [junit4]   2> 2151848 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[7 
(1480362636826640384)]} 0 1
   [junit4]   2> 2151852 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[8 
(1480362636831883264)]} 0 1
   [junit4]   2> 2151856 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[8 
(1480362636835028992)]} 0 1
   [junit4]   2> 2151860 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[9 
(1480362636840271872)]} 0 1
   [junit4]   2> 2151865 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[9 
(1480362636845514752)]} 0 1
   [junit4]   2> 2151869 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[10 
(1480362636848660480)]} 0 1
   [junit4]   2> 2151874 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[10 
(1480362636853903360)]} 0 1
   [junit4]   2> 2151879 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[11 
(1480362636860194816)]} 0 1
   [junit4]   2> 2151883 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[11 
(1480362636863340544)]} 0 1
   [junit4]   2> 2151888 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[12 
(1480362636868583424)]} 0 2
   [junit4]   2> 2151892 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[12 
(1480362636873826304)]} 0 0
   [junit4]   2> 2151899 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[14 
(1480362636879069184)]} 0 2
   [junit4]   2> 2151904 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[14 
(1480362636886409216)]} 0 0
   [junit4]   2> 2151910 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[15 
(1480362636892700672)]} 0 0
   [junit4]   2> 2151916 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[15 
(1480362636897943552)]} 0 1
   [junit4]   2> 2151922 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[16 
(1480362636904235008)]} 0 1
   [junit4]   2> 2151926 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[16 
(1480362636909477888)]} 0 0
   [junit4]   2> 2151932 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[17 
(1480362636914720768)]} 0 2
   [junit4]   2> 2151937 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[17 
(1480362636919963648)]} 0 1
   [junit4]   2> 2151941 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[18 
(1480362636924157952)]} 0 1
   [junit4]   2> 2151946 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[18 
(1480362636929400832)]} 0 2
   [junit4]   2> 2151951 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[19 
(1480362636935692288)]} 0 0
   [junit4]   2> 2151956 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[19 
(1480362636939886592)]} 0 1
   [junit4]   2> 2151960 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[20 
(1480362636944080896)]} 0 1
   [junit4]   2> 2151965 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[20 
(1480362636949323776)]} 0 1
   [junit4]   2> 2151969 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[21 
(1480362636954566656)]} 0 0
   [junit4]   2> 2151973 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[21 
(1480362636958760960)]} 0 1
   [junit4]   2> 2151977 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[22 
(1480362636961906688)]} 0 1
   [junit4]   2> 2151981 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[22 
(1480362636966100992)]} 0 1
   [junit4]   2> 2151986 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[23 
(1480362636971343872)]} 0 1
   [junit4]   2> 2151989 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[23 
(1480362636975538176)]} 0 1
   [junit4]   2> 2151993 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[100 
(1480362636979732480)]} 0 1
   [junit4]   2> 2151997 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[100 
(1480362636983926784)]} 0 0
   [junit4]   2> 2152003 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[101 
(1480362636989169664)]} 0 1
   [junit4]   2> 2152006 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[101 
(1480362636993363968)]} 0 0
   [junit4]   2> 2152010 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[102 
(1480362636996509696)]} 0 1
   [junit4]   2> 2152014 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[102 
(1480362637001752576)]} 0 1
   [junit4]   2> 2152017 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[103 
(1480362637004898304)]} 0 0
   [junit4]   2> 2152026 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[103 
(1480362637012238336)]} 0 2
   [junit4]   2> 2152031 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[104 
(1480362637018529792)]} 0 1
   [junit4]   2> 2152035 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[104 
(1480362637022724096)]} 0 1
   [junit4]   2> 2152039 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[105 
(1480362637026918400)]} 0 1
   [junit4]   2> 2152047 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[105 
(1480362637035307008)]} 0 1
   [junit4]   2> 2152052 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[106 
(1480362637040549888)]} 0 1
   [junit4]   2> 2152055 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[106 
(1480362637044744192)]} 0 0
   [junit4]   2> 2152059 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[107 
(1480362637048938496)]} 0 0
   [junit4]   2> 2152063 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[107 
(1480362637053132800)]} 0 0
   [junit4]   2> 2152068 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[108 
(1480362637058375680)]} 0 1
   [junit4]   2> 2152072 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[108 
(1480362637062569984)]} 0 1
   [junit4]   2> 2152076 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[109 
(1480362637066764288)]} 0 0
   [junit4]   2> 2152081 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[109 
(1480362637070958592)]} 0 1
   [junit4]   2> 2152088 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[110 
(1480362637078298624)]} 0 1
   [junit4]   2> 2152094 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[110 
(1480362637084590080)]} 0 1
   [junit4]   2> 2152100 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[111 
(1480362637090881536)]} 0 1
   [junit4]   2> 2152108 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[111 
(1480362637099270144)]} 0 2
   [junit4]   2> 2152119 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[112 
(1480362637111853056)]} 0 1
   [junit4]   2> 2152131 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[112 
(1480362637122338816)]} 0 2
   [junit4]   2> 2152138 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[113 
(1480362637130727424)]} 0 1
   [junit4]   2> 2152142 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[113 
(1480362637135970304)]} 0 1
   [junit4]   2> 2152146 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[114 
(1480362637139116032)]} 0 1
   [junit4]   2> 2152150 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[114 
(1480362637143310336)]} 0 1
   [junit4]   2> 2152155 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[115 
(1480362637148553216)]} 0 1
   [junit4]   2> 2152159 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[115 
(1480362637152747520)]} 0 1
   [junit4]   2> 2152163 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[116 
(1480362637157990400)]} 0 0
   [junit4]   2> 2152170 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[116 
(1480362637164281856)]} 0 1
   [junit4]   2> 2152174 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[117 
(1480362637168476160)]} 0 1
   [junit4]   2> 2152177 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[117 
(1480362637172670464)]} 0 0
   [junit4]   2> 2152181 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[118 
(1480362637176864768)]} 0 1
   [junit4]   2> 2152185 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[118 
(1480362637181059072)]} 0 1
   [junit4]   2> 2152189 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[119 
(1480362637185253376)]} 0 0
   [junit4]   2> 2152194 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[119 
(1480362637189447680)]} 0 1
   [junit4]   2> 2152198 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[120 
(1480362637194690560)]} 0 0
   [junit4]   2> 2152202 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[120 
(1480362637198884864)]} 0 0
   [junit4]   2> 2152206 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[121 
(1480362637203079168)]} 0 0
   [junit4]   2> 2152211 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[121 
(1480362637208322048)]} 0 0
   [junit4]   2> 2152215 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[122 
(1480362637212516352)]} 0 0
   [junit4]   2> 2152219 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[122 
(1480362637216710656)]} 0 1
   [junit4]   2> 2152223 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[123 
(1480362637220904960)]} 0 0
   [junit4]   2> 2152228 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[123 
(1480362637225099264)]} 0 1
   [junit4]   2> 2152232 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[124 
(1480362637229293568)]} 0 1
   [junit4]   2> 2152236 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[124 
(1480362637234536448)]} 0 0
   [junit4]   2> 2152241 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[125 
(1480362637239779328)]} 0 1
   [junit4]   2> 2152245 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[125 
(1480362637242925056)]} 0 1
   [junit4]   2> 2152248 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[126 
(1480362637247119360)]} 0 0
   [junit4]   2> 2152252 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[126 
(1480362637251313664)]} 0 1
   [junit4]   2> 2152256 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[127 
(1480362637254459392)]} 0 1
   [junit4]   2> 2152259 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[127 
(1480362637258653696)]} 0 0
   [junit4]   2> 2152263 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[128 
(1480362637262848000)]} 0 0
   [junit4]   2> 2152267 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[128 
(1480362637267042304)]} 0 0
   [junit4]   2> 2152271 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[129 
(1480362637271236608)]} 0 1
   [junit4]   2> 2152275 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[129 
(1480362637275430912)]} 0 0
   [junit4]   2> 2152280 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[130 
(1480362637279625216)]} 0 1
   [junit4]   2> 2152285 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[130 
(1480362637284868096)]} 0 1
   [junit4]   2> 2152289 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[131 
(1480362637289062400)]} 0 1
   [junit4]   2> 2152294 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[131 
(1480362637295353856)]} 0 1
   [junit4]   2> 2152298 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[132 
(1480362637298499584)]} 0 1
   [junit4]   2> 2152306 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[132 
(1480362637306888192)]} 0 1
   [junit4]   2> 2152309 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[133 
(1480362637311082496)]} 0 0
   [junit4]   2> 2152315 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[133 
(1480362637317373952)]} 0 1
   [junit4]   2> 2152322 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[134 
(1480362637323665408)]} 0 1
   [junit4]   2> 2152326 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[134 
(1480362637328908288)]} 0 0
   [junit4]   2> 2152330 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[135 
(1480362637332054016)]} 0 1
   [junit4]   2> 2152334 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[135 
(1480362637336248320)]} 0 1
   [junit4]   2> 2152339 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[136 
(1480362637341491200)]} 0 1
   [junit4]   2> 2152346 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[136 
(1480362637348831232)]} 0 1
   [junit4]   2> 2152350 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[137 
(1480362637354074112)]} 0 1
   [junit4]   2> 2152354 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[137 
(1480362637357219840)]} 0 1
   [junit4]   2> 2152358 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[138 
(1480362637362462720)]} 0 0
   [junit4]   2> 2152362 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[138 
(1480362637366657024)]} 0 0
   [junit4]   2> 2152366 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[139 
(1480362637370851328)]} 0 0
   [junit4]   2> 2152370 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[139 
(1480362637375045632)]} 0 0
   [junit4]   2> 2152377 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[140 
(1480362637381337088)]} 0 2
   [junit4]   2> 2152383 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[140 
(1480362637386579968)]} 0 3
   [junit4]   2> 2152390 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[141 
(1480362637393920000)]} 0 2
   [junit4]   2> 2152394 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[141 
(1480362637400211456)]} 0 1
   [junit4]   2> 2152399 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[142 
(1480362637405454336)]} 0 0
   [junit4]   2> 2152404 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[142 
(1480362637409648640)]} 0 1
   [junit4]   2> 2152409 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[143 
(1480362637414891520)]} 0 1
   [junit4]   2> 2152412 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[143 
(1480362637419085824)]} 0 0
   [junit4]   2> 2152417 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[144 
(1480362637423280128)]} 0 2
   [junit4]   2> 2152422 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[144 
(1480362637429571584)]} 0 0
   [junit4]   2> 2152427 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[145 
(1480362637433765888)]} 0 1
   [junit4]   2> 2152432 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[145 
(1480362637439008768)]} 0 1
   [junit4]   2> 2152437 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[146 
(1480362637445300224)]} 0 1
   [junit4]   2> 2152441 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[146 
(1480362637448445952)]} 0 1
   [junit4]   2> 2152446 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[147 
(1480362637453688832)]} 0 1
   [junit4]   2> 2152450 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[147 
(1480362637457883136)]} 0 1
   [junit4]   2> 2152454 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[148 
(1480362637463126016)]} 0 1
   [junit4]   2> 2152460 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[148 
(1480362637468368896)]} 0 1
   [junit4]   2> 2152464 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[149 
(1480362637472563200)]} 0 1
   [junit4]   2> 2152468 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[149 
(1480362637476757504)]} 0 1
   [junit4]   2> 2152473 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[500 
(1480362637482000384)]} 0 1
   [junit4]   2> 2152478 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[500 
(1480362637487243264)]} 0 1
   [junit4]   2> 2152481 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[501 
(1480362637491437568)]} 0 0
   [junit4]   2> 2152485 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[501 
(1480362637495631872)]} 0 1
   [junit4]   2> 2152489 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[502 
(1480362637499826176)]} 0 0
   [junit4]   2> 2152493 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[502 
(1480362637504020480)]} 0 0
   [junit4]   2> 2152498 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[503 
(1480362637508214784)]} 0 0
   [junit4]   2> 2152502 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[503 
(1480362637512409088)]} 0 1
   [junit4]   2> 2152506 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[504 
(1480362637517651968)]} 0 1
   [junit4]   2> 2152511 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[504 
(1480362637521846272)]} 0 1
   [junit4]   2> 2152515 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[505 
(1480362637527089152)]} 0 0
   [junit4]   2> 2152520 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[505 
(1480362637532332032)]} 0 1
   [junit4]   2> 2152526 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[506 
(1480362637537574912)]} 0 1
   [junit4]   2> 2152550 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[506 
(1480362637561692160)]} 0 1
   [junit4]   2> 2152558 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[507 
(1480362637570080768)]} 0 2
   [junit4]   2> 2152562 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[507 
(1480362637576372224)]} 0 0
   [junit4]   2> 2152567 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[508 
(1480362637580566528)]} 0 1
   [junit4]   2> 2152572 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[508 
(1480362637586857984)]} 0 1
   [junit4]   2> 2152578 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[509 
(1480362637593149440)]} 0 0
   [junit4]   2> 2152583 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[509 
(1480362637597343744)]} 0 1
   [junit4]   2> 2152588 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[510 
(1480362637603635200)]} 0 0
   [junit4]   2> 2152592 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[510 
(1480362637607829504)]} 0 0
   [junit4]   2> 2152597 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[511 
(1480362637613072384)]} 0 0
   [junit4]   2> 2152603 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[511 
(1480362637618315264)]} 0 1
   [junit4]   2> 2152607 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[512 
(1480362637623558144)]} 0 1
   [junit4]   2> 2152617 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[512 
(1480362637632995328)]} 0 1
   [junit4]   2> 2152621 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[513 
(1480362637638238208)]} 0 0
   [junit4]   2> 2152627 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[513 
(1480362637643481088)]} 0 1
   [junit4]   2> 2152632 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[514 
(1480362637648723968)]} 0 1
   [junit4]   2> 2152636 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[514 
(1480362637652918272)]} 0 1
   [junit4]   2> 2152639 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[515 
(1480362637657112576)]} 0 0
   [junit4]   2> 2152643 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[515 
(1480362637660258304)]} 0 1
   [junit4]   2> 2152646 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[516 
(1480362637664452608)]} 0 0
   [junit4]   2> 2152651 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[516 
(1480362637669695488)]} 0 0
   [junit4]   2> 2152656 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[517 
(1480362637674938368)]} 0 0
   [junit4]   2> 2152660 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[517 
(1480362637678084096)]} 0 1
   [junit4]   2> 2152665 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[518 
(1480362637683326976)]} 0 1
   [junit4]   2> 2152668 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[518 
(1480362637687521280)]} 0 0
   [junit4]   2> 2152672 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[519 
(1480362637691715584)]} 0 0
   [junit4]   2> 2152677 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[519 
(1480362637695909888)]} 0 1
   [junit4]   2> 2152682 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[520 
(1480362637701152768)]} 0 1
   [junit4]   2> 2152689 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[520 
(1480362637709541376)]} 0 0
   [junit4]   2> 2152692 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[521 
(1480362637712687104)]} 0 0
   [junit4]   2> 2152700 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[521 
(1480362637721075712)]} 0 1
   [junit4]   2> 2152709 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[522 
(1480362637729464320)]} 0 1
   [junit4]   2> 2152712 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[522 
(1480362637733658624)]} 0 0
   [junit4]   2> 2152716 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[523 
(1480362637736804352)]} 0 1
   [junit4]   2> 2152721 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[523 
(1480362637742047232)]} 0 1
   [junit4]   2> 2152724 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[524 
(1480362637746241536)]} 0 0
   [junit4]   2> 2152728 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[524 
(1480362637750435840)]} 0 0
   [junit4]   2> 2152732 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[525 
(1480362637753581568)]} 0 1
   [junit4]   2> 2152736 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[525 
(1480362637758824448)]} 0 0
   [junit4]   2> 2152739 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[526 
(1480362637761970176)]} 0 0
   [junit4]   2> 2152743 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[526 
(1480362637765115904)]} 0 1
   [junit4]   2> 2152747 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[527 
(1480362637770358784)]} 0 0
   [junit4]   2> 2152752 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[527 
(1480362637774553088)]} 0 1
   [junit4]   2> 2152758 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[528 
(1480362637780844544)]} 0 0
   [junit4]   2> 2152761 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[528 
(1480362637785038848)]} 0 0
   [junit4]   2> 2152765 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[529 
(1480362637789233152)]} 0 0
   [junit4]   2> 2152770 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[529 
(1480362637793427456)]} 0 1
   [junit4]   2> 2152773 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[530 
(1480362637797621760)]} 0 0
   [junit4]   2> 2152777 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[530 
(1480362637800767488)]} 0 1
   [junit4]   2> 2152780 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[531 
(1480362637804961792)]} 0 0
   [junit4]   2> 2152783 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[531 
(1480362637808107520)]} 0 0
   [junit4]   2> 2152787 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[532 
(1480362637812301824)]} 0 1
   [junit4]   2> 2152792 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[532 
(1480362637816496128)]} 0 1
   [junit4]   2> 2152797 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[533 
(1480362637822787584)]} 0 0
   [junit4]   2> 2152801 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[533 
(1480362637826981888)]} 0 0
   [junit4]   2> 2152805 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[534 
(1480362637831176192)]} 0 0
   [junit4]   2> 2152809 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[534 
(1480362637835370496)]} 0 0
   [junit4]   2> 2152813 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[535 
(1480362637839564800)]} 0 1
   [junit4]   2> 2152817 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[535 
(1480362637843759104)]} 0 1
   [junit4]   2> 2152823 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[536 
(1480362637850050560)]} 0 0
   [junit4]   2> 2152827 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[536 
(1480362637854244864)]} 0 0
   [junit4]   2> 2152832 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[537 
(1480362637858439168)]} 0 1
   [junit4]   2> 2152837 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[537 
(1480362637864730624)]} 0 1
   [junit4]   2> 2152842 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[538 
(1480362637869973504)]} 0 1
   [junit4]   2> 2152848 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[538 
(1480362637875216384)]} 0 1
   [junit4]   2> 2152852 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[539 
(1480362637880459264)]} 0 1
   [junit4]   2> 2152857 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[539 
(1480362637885702144)]} 0 0
   [junit4]   2> 2152862 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[540 
(1480362637889896448)]} 0 1
   [junit4]   2> 2152866 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[540 
(1480362637895139328)]} 0 0
   [junit4]   2> 2152871 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[541 
(1480362637899333632)]} 0 1
   [junit4]   2> 2152875 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[541 
(1480362637904576512)]} 0 0
   [junit4]   2> 2152880 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[542 
(1480362637908770816)]} 0 1
   [junit4]   2> 2152884 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[542 
(1480362637914013696)]} 0 0
   [junit4]   2> 2152890 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[543 
(1480362637920305152)]} 0 0
   [junit4]   2> 2152896 T4883 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[543 
(1480362637925548032)]} 0 1
   [junit4]   2> 2152900 T4871 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[544 
(1480362637929742336)]} 0 1
   [junit4]   2> 2152904 T4887 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[544 
(1480362637934985216)]} 0 1
   [junit4]   2> 2152908 T4872 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[545 
(1480362637939179520)]} 0 0
   [junit4]   2> 2152913 T4885 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[545 
(1480362637943373824)]} 0 1
   [junit4]   2> 2152917 T4873 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[546 
(1480362637947568128)]} 0 1
   [junit4]   2> 2152922 T4886 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[546 
(1480362637953859584)]} 0 0
   [junit4]   2> 2152926 T4874 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[547 
(1480362637958053888)]} 0 1
   [junit4]   2> 2152930 T4888 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[547 
(1480362637962248192)]} 0 1
   [junit4]   2> 2152934 T4875 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[548 
(1480362637966442496)]} 0 0
   [junit4]   2> 2152939 T4884 C739 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[548 
(1480362637970636800)]} 0 1
   [junit4]   2> 2152943 T4876 C738 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/fe_ path=/update params={wt=javabin&version=2} {add=[549 
(1480362637975879680)]} 0 1
 

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

deletesByQuery=0,errors=0,cumulative_adds=137,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=14497,transaction_logs_total_number=1}
   [junit4]   2> 2186174 T4867 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 2186175 T4867 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 2186175 T4867 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 2186179 T4867 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 2186179 T4867 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 2186180 T4867 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard1/data/index
 
[CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard1/data/index;done=false>>]
   [junit4]   2> 2186181 T4867 oasc.CachingDirectoryFactory.close Closing 
directory: 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard1/data/index
   [junit4]   2> 2186181 T4867 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard1/data
 
[CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard1/data;done=false>>]
   [junit4]   2> 2186182 T4867 oasc.CachingDirectoryFactory.close Closing 
directory: 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001/tempDir-001/shard1/data
   [junit4]   2> 2186183 T4867 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/fe_,null}
   [junit4]   2> 2191129 T4867 oas.SolrTestCaseJ4.tearDown ###Ending 
testDistribSearch
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedGrouping -Dtests.method=testDistribSearch 
-Dtests.seed=71C929CD17DE5EBF -Dtests.slow=true -Dtests.locale=lv 
-Dtests.timezone=Etc/GMT+6 -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   41.8s | TestDistributedGrouping.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([71C929CD17DE5EBF:F02FA7D560813E83]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:568)
   [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.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:512)
   [junit4]    >        at 
org.apache.solr.TestDistributedGrouping.simpleQuery(TestDistributedGrouping.java:274)
   [junit4]    >        at 
org.apache.solr.TestDistributedGrouping.doTest(TestDistributedGrouping.java:262)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:875)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2191150 T4867 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping-71C929CD17DE5EBF-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene50): 
{timestamp=OrdsLucene41(blocksize=128), _version_=FSTOrd41, 
foo_f=PostingsFormat(name=Memory doPackFST= true), 
n_d1=OrdsLucene41(blocksize=128), 
foo_d=PostingsFormat(name=Lucene41VarGapFixedInterval), 
text=PostingsFormat(name=Memory doPackFST= true), 
a_t=OrdsLucene41(blocksize=128), n_td1=FSTOrd41, 
rnd_b=PostingsFormat(name=Lucene41VarGapFixedInterval), 
oddField_s=PostingsFormat(name=Lucene41VarGapFixedInterval), 
a_s=PostingsFormat(name=Memory doPackFST= true), 
range_facet_l=OrdsLucene41(blocksize=128), n_l1=OrdsLucene41(blocksize=128), 
n_ti1=PostingsFormat(name=Lucene41VarGapFixedInterval), n_tl1=FSTOrd41, 
intDefault=FSTOrd41, a_n_tdt=PostingsFormat(name=Memory doPackFST= true), 
multiDefault=OrdsLucene41(blocksize=128), n_f1=FSTOrd41, n_tdt1=FSTOrd41, 
SubjectTerms_mfacet=FSTOrd41, a_i1=OrdsLucene41(blocksize=128), 
other_tl1=FSTOrd41, n_tf1=OrdsLucene41(blocksize=128), 
foo_b=PostingsFormat(name=Memory doPackFST= true), 
id=PostingsFormat(name=Lucene41VarGapFixedInterval), b_n_tdt=FSTOrd41, 
n_dt1=FSTOrd41}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, 
sim=DefaultSimilarity, locale=lv, timezone=Etc/GMT+6
   [junit4]   2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_67 
(64-bit)/cpus=2,threads=1,free=312220568,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [PrimitiveFieldTypeTest, 
SuggesterFSTTest, NotRequiredUniqueKeyTest, TestExceedMaxTermLength, 
TestFileDictionaryLookup, TestCoreContainer, OpenCloseCoreStressTest, 
BinaryUpdateRequestHandlerTest, TestReRankQParserPlugin, CSVRequestHandlerTest, 
ZkControllerTest, ExternalFileFieldSortTest, 
DistributedQueryComponentOptimizationTest, TestCollationFieldDocValues, 
SolrXmlInZkTest, TestArbitraryIndexDir, TestSolrDeletionPolicy2, 
TestUniqueKeyFieldResource, TestPostingsSolrHighlighter, SolrTestCaseJ4Test, 
AddBlockUpdateTest, DistributedFacetPivotLargeTest, SuggesterTSTTest, 
TestSchemaResource, HdfsRecoveryZkTest, TestElisionMultitermQuery, 
TestCopyFieldCollectionResource, TestSolrXml, TestFieldResource, 
TestSearcherReuse, TestDocSet, TestSolrJ, AnalyticsMergeStrategyTest, 
RegexBoostProcessorTest, TriLevelCompositeIdRoutingTest, 
HdfsWriteToMultipleCollectionsTest, TestSchemaSimilarityResource, 
AliasIntegrationTest, TestManagedSchemaDynamicFieldResource, 
SignatureUpdateProcessorFactoryTest, SpatialFilterTest, DocValuesMultiTest, 
TestNonDefinedSimilarityFactory, TestStandardQParsers, 
TestAnalyzeInfixSuggestions, AsyncMigrateRouteKeyTest, TestFiltering, 
SuggestComponentTest, TestRandomDVFaceting, MBeansHandlerTest, TestCustomSort, 
SpellCheckComponentTest, BasicDistributedZkTest, LeaderElectionIntegrationTest, 
CoreMergeIndexesAdminHandlerTest, TestCoreDiscovery, TestFastLRUCache, 
DistanceFunctionTest, HdfsLockFactoryTest, TermVectorComponentDistributedTest, 
SimpleFacetsTest, ModifyConfFileTest, TestIntervalFaceting, HdfsSyncSliceTest, 
RollingRestartTest, UUIDFieldTest, SampleTest, TestSimpleQParserPlugin, 
DocumentBuilderTest, TestOmitPositions, TestConfig, TestMergePolicyConfig, 
DocValuesMissingTest, BlockCacheTest, PeerSyncTest, RemoteQueryErrorTest, 
TestCollectionAPI, SliceStateTest, TestDistributedSearch, TestRTGBase, 
SolrCoreTest, DistributedSpellCheckComponentTest, ChangedSchemaMergeTest, 
NumericFieldsTest, TestCloudInspectUtil, TestQuerySenderListener, 
TestRequestStatusCollectionAPI, TestPseudoReturnFields, 
HdfsBasicDistributedZk2Test, SpellingQueryConverterTest, SynonymTokenizerTest, 
PrimUtilsTest, TestSolrQueryParserDefaultOperatorResource, TestSort, 
TestCharFilters, XmlUpdateRequestHandlerTest, TestFieldCollectionResource, 
TestTrie, TestStressReorder, FileUtilsTest, SharedFSAutoReplicaFailoverTest, 
TestExpandComponent, CollectionsAPIDistributedZkTest, TermVectorComponentTest, 
TestLMJelinekMercerSimilarityFactory, TestSolrXmlPersistence, 
TestClassNameShortening, TestHashPartitioner, HdfsBasicDistributedZkTest, 
RequestHandlersTest, TestFuzzyAnalyzedSuggestions, MultiThreadedOCPTest, 
OverseerTest, StatsComponentTest, ResponseHeaderTest, TimeZoneUtilsTest, 
EnumFieldTest, SolrCmdDistributorTest, UpdateRequestProcessorFactoryTest, 
DirectUpdateHandlerTest, ReturnFieldsTest, AlternateDirectoryTest, 
SolrRequestParserTest, TestLeaderElectionZkExpiry, BasicZkTest, 
InfoHandlerTest, TestBinaryField, TestDistributedGrouping]
   [junit4] Completed in 41.85s, 1 test, 1 error <<< FAILURES!

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

Total time: 184 minutes 50 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0 
-XX:-UseCompressedOops -XX:+UseG1GC
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