You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/11/28 22:06:33 UTC

[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 2900 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2900/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.TestDistributedSearch.test

Error Message:
Error from server at http://127.0.0.1:53337/_l/collection1: java.lang.NullPointerException  at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:105)  at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744)  at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727)  at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:409)  at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:151)  at org.apache.solr.core.SolrCore.execute(SolrCore.java:2072)  at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654)  at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:455)  at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:221)  at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:180)  at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)  at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109)  at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)  at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)  at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)  at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)  at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)  at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)  at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)  at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)  at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)  at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)  at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)  at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)  at org.eclipse.jetty.server.Server.handle(Server.java:499)  at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)  at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)  at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)  at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)  at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)  at java.lang.Thread.run(Thread.java:745) 

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:53337/_l/collection1: java.lang.NullPointerException
	at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:105)
	at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744)
	at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727)
	at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:409)
	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:151)
	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2072)
	at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654)
	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:455)
	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:221)
	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:180)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
	at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
	at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
	at org.eclipse.jetty.server.Server.handle(Server.java:499)
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
	at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
	at java.lang.Thread.run(Thread.java:745)

	at __randomizedtesting.SeedInfo.seed([E5432FD639C98C61:6D17100C9735E199]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:574)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:150)
	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
	at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133)
	at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095)
	at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:986)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	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:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	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:54)
	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:367)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10203 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/init-core-data-001
   [junit4]   2> 1773467 INFO  (SUITE-TestDistributedSearch-seed#[E5432FD639C98C61]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1773468 INFO  (SUITE-TestDistributedSearch-seed#[E5432FD639C98C61]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_l/
   [junit4]   2> 1773758 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1773760 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1773762 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1fb9eced{/_l,null,AVAILABLE}
   [junit4]   2> 1773763 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.e.j.s.ServerConnector Started ServerConnector@3e97b037{HTTP/1.1}{127.0.0.1:53251}
   [junit4]   2> 1773764 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.e.j.s.Server Started @1777695ms
   [junit4]   2> 1773764 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_l, hostPort=53251, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores}
   [junit4]   2> 1773764 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@74a14482
   [junit4]   2> 1773764 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control'
   [junit4]   2> 1773764 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1773764 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1773765 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/solr.xml
   [junit4]   2> 1773774 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores
   [junit4]   2> 1773774 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer New CoreContainer 1980297450
   [junit4]   2> 1773774 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control]
   [junit4]   2> 1773774 WARN  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/lib
   [junit4]   2> 1773774 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1773776 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1773777 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1773777 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1773777 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1773777 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1773778 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoresLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores
   [junit4]   2> 1773778 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/}
   [junit4]   2> 1773778 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoresLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1/
   [junit4]   2> 1773778 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1773779 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1773779 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1773779 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1773779 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
   [junit4]   2> 1773779 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1773779 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1773780 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1773809 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1773815 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1773830 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1773834 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1773905 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1773909 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1773910 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1773913 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1773914 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1773915 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1773916 WARN  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1773916 WARN  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1773916 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1773916 WARN  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1773916 WARN  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1773919 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1773919 INFO  (coreLoadExecutor-5777-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1773919 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1773919 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[null]
   [junit4]   2> 1773919 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@839fdaa
   [junit4]   2> 1773920 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1/data
   [junit4]   2> 1773920 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1/data/index/
   [junit4]   2> 1773920 WARN  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1773920 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1/data/index
   [junit4]   2> 1773920 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=18.7412109375, floorSegmentMB=1.90625, forceMergeDeletesPctAllowed=19.523964494646435, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1773920 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@85fc65a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@69bf55bd),segFN=segments_1,generation=1}
   [junit4]   2> 1773921 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1773922 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1/data/
   [junit4]   2> 1773922 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.DirectoryFactory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/control/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1773924 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1773925 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1773928 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1773929 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1773929 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1773930 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1773933 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1773934 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1773934 WARN  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1773935 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1773935 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1773936 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1773937 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1773937 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1773937 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1773937 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@85fc65a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@69bf55bd),segFN=segments_1,generation=1}
   [junit4]   2> 1773937 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1773937 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3136285[collection1] main
   [junit4]   2> 1773937 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1773938 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1773938 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1773938 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1773974 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1774247 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1774248 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1774248 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1774248 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1774248 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1774248 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 1774248 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1519117722647003136
   [junit4]   2> 1774248 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1774249 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1774249 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1774249 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1774250 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1774250 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1774250 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1519117722647003136
   [junit4]   2> 1774250 INFO  (coreLoadExecutor-5777-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1774251 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1774251 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3136285[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1774792 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1774794 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 1774796 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@454a5f46{/_l,null,AVAILABLE}
   [junit4]   2> 1774796 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.e.j.s.ServerConnector Started ServerConnector@7d78018e{HTTP/1.1}{127.0.0.1:53252}
   [junit4]   2> 1774797 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.e.j.s.Server Started @1778728ms
   [junit4]   2> 1774797 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_l, hostPort=53252, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores}
   [junit4]   2> 1774797 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@74a14482
   [junit4]   2> 1774797 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0'
   [junit4]   2> 1774797 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1774797 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1774797 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1774997 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores
   [junit4]   2> 1774998 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer New CoreContainer 1404013991
   [junit4]   2> 1774998 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0]
   [junit4]   2> 1774998 WARN  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/lib
   [junit4]   2> 1774998 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1775000 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1775000 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1775001 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1775001 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1775001 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1775002 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoresLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores
   [junit4]   2> 1775002 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/}
   [junit4]   2> 1775002 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoresLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1/
   [junit4]   2> 1775003 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1775003 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1775003 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1775003 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1775003 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
   [junit4]   2> 1775004 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1775004 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1775004 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1775033 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1775039 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1775053 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1775057 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1775127 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1775132 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1775133 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1775136 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1775137 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1775138 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1775138 WARN  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1775138 WARN  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1775139 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1775139 WARN  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1775139 WARN  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1775142 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 1775142 INFO  (coreLoadExecutor-5788-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1775142 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1775142 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[null]
   [junit4]   2> 1775142 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@839fdaa
   [junit4]   2> 1775143 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1/data
   [junit4]   2> 1775143 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1/data/index/
   [junit4]   2> 1775143 WARN  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1775143 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1/data/index
   [junit4]   2> 1775143 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=18.7412109375, floorSegmentMB=1.90625, forceMergeDeletesPctAllowed=19.523964494646435, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1775143 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@7292e66 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f9656d8),segFN=segments_1,generation=1}
   [junit4]   2> 1775143 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1775144 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1/data/
   [junit4]   2> 1775144 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.DirectoryFactory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001/tempDir-001/shard0/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1775147 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1775147 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1775147 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1775147 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 1775147 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1775147 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1775147 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1775147 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 1775148 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 1775148 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1775148 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 1775148 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1775151 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1775152 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1775152 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1775153 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1775156 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy 
   [junit4]   2> 1775158 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 1775158 WARN  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
   [junit4]   2> 1775158 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1775159 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1775159 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1775160 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1775160 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1775161 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1775161 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@7292e66 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f9656d8),segFN=segments_1,generation=1}
   [junit4]   2> 1775161 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1775161 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1e8e9704[collection1] main
   [junit4]   2> 1775161 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 1775161 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1775161 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1775161 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1775215 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1775473 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1775473 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1775474 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1775474 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 1775474 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1775474 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1775474 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 1775474 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1775474 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1519117723932557312
   [junit4]   2> 1775474 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1775475 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1775475 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1775475 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1775476 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1519117723932557312
   [junit4]   2> 1775476 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1775476 INFO  (coreLoadExecutor-5788-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1775476 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e8e9704[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1775507 INFO  (TEST-TestDistributedSearch.test-seed#[E5432FD639C98C61]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1775511 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1519117723969257472)} 0 1
   [junit4]   2> 1775515 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1519117723973451776)} 0 1
   [junit4]   2> 1775518 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[1 (1519117723977646080)]} 0 1
   [junit4]   2> 1775521 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[1 (1519117723981840384)]} 0 0
   [junit4]   2> 1775526 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[2 (1519117723986034688)]} 0 1
   [junit4]   2> 1775528 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[2 (1519117723989180416)]} 0 0
   [junit4]   2> 1775531 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[3 (1519117723991277568)]} 0 0
   [junit4]   2> 1775533 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[3 (1519117723993374720)]} 0 0
   [junit4]   2> 1775535 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[4 (1519117723996520448)]} 0 0
   [junit4]   2> 1775538 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[4 (1519117723998617600)]} 0 0
   [junit4]   2> 1775540 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[5 (1519117724000714752)]} 0 0
   [junit4]   2> 1775542 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[5 (1519117724003860480)]} 0 0
   [junit4]   2> 1775546 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[6 (1519117724008054784)]} 0 0
   [junit4]   2> 1775549 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[6 (1519117724010151936)]} 0 0
   [junit4]   2> 1775551 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[7 (1519117724012249088)]} 0 0
   [junit4]   2> 1775553 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[7 (1519117724014346240)]} 0 0
   [junit4]   2> 1775556 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[8 (1519117724017491968)]} 0 0
   [junit4]   2> 1775558 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[8 (1519117724020637696)]} 0 0
   [junit4]   2> 1775561 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[9 (1519117724023783424)]} 0 0
   [junit4]   2> 1775566 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[9 (1519117724027977728)]} 0 0
   [junit4]   2> 1775567 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1775570 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@85fc65a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@69bf55bd),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@85fc65a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@69bf55bd),segFN=segments_2,generation=2}
   [junit4]   2> 1775571 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1775571 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@66209e80[collection1] main
   [junit4]   2> 1775572 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1775572 INFO  (searcherExecutor-5778-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@66209e80[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)))}
   [junit4]   2> 1775573 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 5
   [junit4]   2> 1775575 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1775577 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@7292e66 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f9656d8),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@7292e66 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f9656d8),segFN=segments_2,generation=2}
   [junit4]   2> 1775578 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1775579 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2a23ae0c[collection1] main
   [junit4]   2> 1775579 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1775580 INFO  (searcherExecutor-5789-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2a23ae0c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)))}
   [junit4]   2> 1775580 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 4
   [junit4]   2> 1775585 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[10 (1519117724045803520)]} 0 2
   [junit4]   2> 1775590 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[10 (1519117724051046400)]} 0 2
   [junit4]   2> 1775593 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[11 (1519117724057337856)]} 0 0
   [junit4]   2> 1775596 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[11 (1519117724059435008)]} 0 0
   [junit4]   2> 1775598 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[12 (1519117724062580736)]} 0 0
   [junit4]   2> 1775601 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[12 (1519117724065726464)]} 0 0
   [junit4]   2> 1775606 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[13 (1519117724070969344)]} 0 1
   [junit4]   2> 1775609 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[13 (1519117724074115072)]} 0 0
   [junit4]   2> 1775612 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[1001 (1519117724076212224)]} 0 0
   [junit4]   2> 1775614 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[1001 (1519117724078309376)]} 0 0
   [junit4]   2> 1775617 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[14 (1519117724082503680)]} 0 0
   [junit4]   2> 1775623 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[14 (1519117724085649408)]} 0 2
   [junit4]   2> 1775627 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[15 (1519117724091940864)]} 0 0
   [junit4]   2> 1775629 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[15 (1519117724095086592)]} 0 0
   [junit4]   2> 1775633 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[16 (1519117724098232320)]} 0 1
   [junit4]   2> 1775636 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[16 (1519117724102426624)]} 0 0
   [junit4]   2> 1775641 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[17 (1519117724107669504)]} 0 0
   [junit4]   2> 1775644 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[17 (1519117724109766656)]} 0 0
   [junit4]   2> 1775646 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[100 (1519117724112912384)]} 0 0
   [junit4]   2> 1775648 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[100 (1519117724115009536)]} 0 0
   [junit4]   2> 1775650 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[101 (1519117724117106688)]} 0 0
   [junit4]   2> 1775653 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[101 (1519117724120252416)]} 0 0
   [junit4]   2> 1775655 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[102 (1519117724122349568)]} 0 0
   [junit4]   2> 1775657 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[102 (1519117724124446720)]} 0 0
   [junit4]   2> 1775659 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[103 (1519117724126543872)]} 0 0
   [junit4]   2> 1775661 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[103 (1519117724128641024)]} 0 0
   [junit4]   2> 1775665 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[104 (1519117724131786752)]} 0 0
   [junit4]   2> 1775669 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[104 (1519117724137029632)]} 0 1
   [junit4]   2> 1775671 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[105 (1519117724139126784)]} 0 0
   [junit4]   2> 1775673 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[105 (1519117724141223936)]} 0 0
   [junit4]   2> 1775677 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[106 (1519117724143321088)]} 0 1
   [junit4]   2> 1775682 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[106 (1519117724149612544)]} 0 1
   [junit4]   2> 1775686 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[107 (1519117724154855424)]} 0 0
   [junit4]   2> 1775690 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[107 (1519117724158001152)]} 0 0
   [junit4]   2> 1775694 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[108 (1519117724162195456)]} 0 1
   [junit4]   2> 1775696 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[108 (1519117724165341184)]} 0 0
   [junit4]   2> 1775698 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[109 (1519117724167438336)]} 0 0
   [junit4]   2> 1775713 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[109 (1519117724170584064)]} 0 12
   [junit4]   2> 1775716 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[110 (1519117724186312704)]} 0 0
   [junit4]   2> 1775719 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[110 (1519117724188409856)]} 0 0
   [junit4]   2> 1775721 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[111 (1519117724191555584)]} 0 0
   [junit4]   2> 1775724 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[111 (1519117724194701312)]} 0 0
   [junit4]   2> 1775727 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[112 (1519117724196798464)]} 0 0
   [junit4]   2> 1775731 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[112 (1519117724200992768)]} 0 0
   [junit4]   2> 1775735 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[113 (1519117724205187072)]} 0 0
   [junit4]   2> 1775739 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[113 (1519117724210429952)]} 0 0
   [junit4]   2> 1775742 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[114 (1519117724212527104)]} 0 0
   [junit4]   2> 1775744 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[114 (1519117724215672832)]} 0 0
   [junit4]   2> 1775747 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[115 (1519117724218818560)]} 0 0
   [junit4]   2> 1775750 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[115 (1519117724221964288)]} 0 0
   [junit4]   2> 1775754 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[116 (1519117724225110016)]} 0 0
   [junit4]   2> 1775757 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[116 (1519117724228255744)]} 0 0
   [junit4]   2> 1775761 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[117 (1519117724233498624)]} 0 0
   [junit4]   2> 1775765 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[117 (1519117724236644352)]} 0 0
   [junit4]   2> 1775768 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[118 (1519117724240838656)]} 0 0
   [junit4]   2> 1775772 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[118 (1519117724245032960)]} 0 0
   [junit4]   2> 1775775 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[119 (1519117724248178688)]} 0 0
   [junit4]   2> 1775777 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[119 (1519117724250275840)]} 0 0
   [junit4]   2> 1775779 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[120 (1519117724252372992)]} 0 0
   [junit4]   2> 1775781 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[120 (1519117724254470144)]} 0 0
   [junit4]   2> 1775783 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[121 (1519117724256567296)]} 0 0
   [junit4]   2> 1775785 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[121 (1519117724258664448)]} 0 0
   [junit4]   2> 1775787 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[122 (1519117724260761600)]} 0 0
   [junit4]   2> 1775790 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[122 (1519117724262858752)]} 0 0
   [junit4]   2> 1775792 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[123 (1519117724266004480)]} 0 0
   [junit4]   2> 1775794 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[123 (1519117724268101632)]} 0 0
   [junit4]   2> 1775796 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[124 (1519117724270198784)]} 0 0
   [junit4]   2> 1775798 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[124 (1519117724272295936)]} 0 0
   [junit4]   2> 1775800 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[125 (1519117724274393088)]} 0 0
   [junit4]   2> 1775802 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[125 (1519117724276490240)]} 0 0
   [junit4]   2> 1775805 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[126 (1519117724278587392)]} 0 0
   [junit4]   2> 1775807 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[126 (1519117724281733120)]} 0 0
   [junit4]   2> 1775810 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[127 (1519117724284878848)]} 0 0
   [junit4]   2> 1775812 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[127 (1519117724286976000)]} 0 0
   [junit4]   2> 1775816 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[128 (1519117724291170304)]} 0 0
   [junit4]   2> 1775821 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[128 (1519117724296413184)]} 0 0
   [junit4]   2> 1775823 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[129 (1519117724298510336)]} 0 0
   [junit4]   2> 1775826 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[129 (1519117724300607488)]} 0 0
   [junit4]   2> 1775828 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[130 (1519117724302704640)]} 0 0
   [junit4]   2> 1775830 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[130 (1519117724305850368)]} 0 0
   [junit4]   2> 1775832 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[131 (1519117724307947520)]} 0 0
   [junit4]   2> 1775836 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[131 (1519117724311093248)]} 0 0
   [junit4]   2> 1775838 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[132 (1519117724314238976)]} 0 0
   [junit4]   2> 1775842 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[132 (1519117724318433280)]} 0 0
   [junit4]   2> 1775844 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[133 (1519117724320530432)]} 0 0
   [junit4]   2> 1775846 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[133 (1519117724322627584)]} 0 0
   [junit4]   2> 1775849 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[134 (1519117724325773312)]} 0 0
   [junit4]   2> 1775853 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[134 (1519117724328919040)]} 0 0
   [junit4]   2> 1775857 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[135 (1519117724333113344)]} 0 0
   [junit4]   2> 1775861 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[135 (1519117724338356224)]} 0 0
   [junit4]   2> 1775863 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[136 (1519117724340453376)]} 0 0
   [junit4]   2> 1775865 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[136 (1519117724342550528)]} 0 0
   [junit4]   2> 1775867 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[137 (1519117724344647680)]} 0 0
   [junit4]   2> 1775870 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[137 (1519117724347793408)]} 0 0
   [junit4]   2> 1775872 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[138 (1519117724349890560)]} 0 0
   [junit4]   2> 1775874 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[138 (1519117724351987712)]} 0 0
   [junit4]   2> 1775877 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[139 (1519117724354084864)]} 0 0
   [junit4]   2> 1775879 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[139 (1519117724356182016)]} 0 0
   [junit4]   2> 1775881 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[140 (1519117724359327744)]} 0 0
   [junit4]   2> 1775885 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[140 (1519117724362473472)]} 0 0
   [junit4]   2> 1775887 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[141 (1519117724364570624)]} 0 0
   [junit4]   2> 1775889 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[141 (1519117724367716352)]} 0 0
   [junit4]   2> 1775893 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[142 (1519117724371910656)]} 0 0
   [junit4]   2> 1775895 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[142 (1519117724374007808)]} 0 0
   [junit4]   2> 1775897 INFO  (qtp608680998-12975) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[143 (1519117724376104960)]} 0 0
   [junit4]   2> 1775901 INFO  (qtp213576601-12993) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[143 (1519117724380299264)]} 0 0
   [junit4]   2> 1775903 INFO  (qtp608680998-12976) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[144 (1519117724382396416)]} 0 0
   [junit4]   2> 1775905 INFO  (qtp213576601-12994) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[144 (1519117724384493568)]} 0 0
   [junit4]   2> 1775907 INFO  (qtp608680998-12977) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[145 (1519117724386590720)]} 0 0
   [junit4]   2> 1775909 INFO  (qtp213576601-12995) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[145 (1519117724388687872)]} 0 0
   [junit4]   2> 1775911 INFO  (qtp608680998-12972) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[146 (1519117724390785024)]} 0 0
   [junit4]   2> 1775913 INFO  (qtp213576601-12990) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[146 (1519117724392882176)]} 0 0
   [junit4]   2> 1775915 INFO  (qtp608680998-12973) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[147 (1519117724394979328)]} 0 0
   [junit4]   2> 1775918 INFO  (qtp213576601-12991) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[147 (1519117724397076480)]} 0 0
   [junit4]   2> 1775920 INFO  (qtp608680998-12974) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params={wt=javabin&version=2} {add=[148 (1519117724399173632)]} 0 0
   [junit4]   2> 1775922 INFO  (qtp213576601-12992) [    x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/_l path=/update params

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

search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:105)
   [junit4]    > 	at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744)
   [junit4]    > 	at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727)
   [junit4]    > 	at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:409)
   [junit4]    > 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:151)
   [junit4]    > 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2072)
   [junit4]    > 	at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654)
   [junit4]    > 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:455)
   [junit4]    > 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:221)
   [junit4]    > 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:180)
   [junit4]    > 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]    > 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109)
   [junit4]    > 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]    > 	at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
   [junit4]    > 	at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
   [junit4]    > 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
   [junit4]    > 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
   [junit4]    > 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
   [junit4]    > 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
   [junit4]    > 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
   [junit4]    > 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]    > 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
   [junit4]    > 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]    > 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
   [junit4]    > 	at org.eclipse.jetty.server.Server.handle(Server.java:499)
   [junit4]    > 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
   [junit4]    > 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
   [junit4]    > 	at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
   [junit4]    > 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
   [junit4]    > 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([E5432FD639C98C61:6D17100C9735E199]:0)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:574)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:150)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
   [junit4]    > 	at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133)
   [junit4]    > 	at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095)
   [junit4]    > 	at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:986)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1880619 INFO  (SUITE-TestDistributedSearch-seed#[E5432FD639C98C61]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E5432FD639C98C61-001
   [junit4]   2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=3, maxDocsPerChunk=8, blockSize=6), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=3, blockSize=6)), sim=ClassicSimilarity, locale=ar_SA, timezone=America/Argentina/Ushuaia
   [junit4]   2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=3,threads=1,free=127699184,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolrDynamicMBean, AddSchemaFieldsUpdateProcessorFactoryTest, SecurityConfHandlerTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, DocumentBuilderTest, TestSchemaResource, DistributedQueryComponentOptimizationTest, TestLRUStatsCache, SimpleCollectionCreateDeleteTest, TestDistributedMissingSort, BasicDistributedZkTest, TestCryptoKeys, ShardSplitTest, SpatialRPTFieldTypeTest, UnloadDistributedZkTest, CursorPagingTest, ExplicitHLLTest, SpatialFilterTest, PolyFieldTest, TestExclusionRuleCollectionAccess, UUIDFieldTest, MultiThreadedOCPTest, HLLUtilTest, TestObjectReleaseTracker, TestSchemaNameResource, TestStressVersions, HdfsChaosMonkeySafeLeaderTest, TestOrdValues, TestCodecSupport, RankQueryTest, DistributedSpellCheckComponentTest, AddBlockUpdateTest, CdcrUpdateLogTest, TestPartialUpdateDeduplication, StandardRequestHandlerTest, AnalyticsMergeStrategyTest, TestSmileRequest, TestXIncludeConfig, TestQueryWrapperFilter, TestOverriddenPrefixQueryForCustomFieldType, TestRandomMergePolicy, TestMinMaxOnMultiValuedField, TestUpdate, PrimUtilsTest, CoreAdminCreateDiscoverTest, OverseerTest, SimpleMLTQParserTest, TestCustomDocTransformer, TestSweetSpotSimilarityFactory, SaslZkACLProviderTest, TestHashQParserPlugin, XmlUpdateRequestHandlerTest, TestDFRSimilarityFactory, DistributedQueryElevationComponentTest, DateRangeFieldTest, RequiredFieldsTest, TimeZoneUtilsTest, TestStressReorder, SparseHLLTest, TestReplicationHandlerBackup, TestStressUserVersions, DocumentAnalysisRequestHandlerTest, RecoveryZkTest, TestHighlightDedupGrouping, SyncSliceTest, CdcrReplicationHandlerTest, IndexBasedSpellCheckerTest, CircularListTest, ShardRoutingTest, TestBulkSchemaAPI, TestSimpleQParserPlugin, TestFaceting, TestLMDirichletSimilarityFactory, TestConfigReload, HdfsRecoverLeaseTest, PathHierarchyTokenizerFactoryTest, MBeansHandlerTest, TestMissingGroups, DocValuesMultiTest, TestNoOpRegenerator, MigrateRouteKeyTest, DistributedFacetPivotLargeTest, SolrCloudExampleTest, ScriptEngineTest, TestGroupingSearch, MergeStrategyTest, CollectionTooManyReplicasTest, DocValuesTest, TestHashPartitioner, TestExtendedDismaxParser, TestRemoteStreaming, DistribCursorPagingTest, CloudMLTQParserTest, AnalysisAfterCoreReloadTest, TestLazyCores, CdcrVersionReplicationTest, TestComponentsName, SolrIndexConfigTest, TestLFUCache, DirectSolrSpellCheckerTest, DeleteLastCustomShardedReplicaTest, TestDocSet, TestJmxIntegration, TestReplicationHandler, SolrRequestParserTest, DisMaxRequestHandlerTest, TestDynamicLoading, XsltUpdateRequestHandlerTest, TestReloadAndDeleteDocs, TestAnalyzedSuggestions, TestWordDelimiterFilterFactory, TestManagedSchemaFieldResource, TestRestoreCore, TestReqParamsAPI, SimpleFacetsTest, SegmentsInfoRequestHandlerTest, TestJsonRequest, CoreAdminRequestStatusTest, ConfigSetsAPITest, TestMiniSolrCloudCluster, TestSchemaVersionResource, QueryElevationComponentTest, ReturnFieldsTest, TestLRUCache, TestStressLucene, PreAnalyzedFieldTest, ConcurrentDeleteAndCreateCollectionTest, LeaderElectionTest, TestMiniSolrCloudClusterBase, TestCoreContainer, TestLeaderInitiatedRecoveryThread, SchemaVersionSpecificBehaviorTest, TestHdfsUpdateLog, FacetPivotSmallTest, BasicDistributedZk2Test, BasicZkTest, ClusterStateUpdateTest, TestRandomFaceting, ZkSolrClientTest, ShardRoutingCustomTest, TestDistributedSearch]
   [junit4] Completed [309/562] on J0 in 107.18s, 1 test, 1 error <<< FAILURES!

[...truncated 816 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:775: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:719: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:233: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:516: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1432: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:987: There were test failures: 562 suites (8 ignored), 2228 tests, 1 error, 111 ignored (67 assumptions) [seed: E5432FD639C98C61]

Total time: 70 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any