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 2016/03/20 19:12:41 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_72) - Build #
5723 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/5723/
Java: 64bit/jdk1.8.0_72 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Error from server at http://127.0.0.1:64900//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:753) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:736) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:420) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2033) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:652) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:229) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:184) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:518) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) 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:64900//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:753)
at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:736)
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:420)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2033)
at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:652)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:229)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:184)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
at org.eclipse.jetty.server.Server.handle(Server.java:518)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([34C2AABA11A78881:BC969560BF5BE579]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1137)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1099)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:961)
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:498)
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:1022)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
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:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
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 10928 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\init-core-data-001
[junit4] 2> 531050 INFO (SUITE-TestDistributedSearch-seed#[34C2AABA11A78881]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 531050 INFO (SUITE-TestDistributedSearch-seed#[34C2AABA11A78881]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 531778 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1
[junit4] 2> 531781 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 531782 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f77d878{/,null,AVAILABLE}
[junit4] 2> 531783 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.ServerConnector Started ServerConnector@218345f2{HTTP/1.1,[http/1.1]}{127.0.0.1:64593}
[junit4] 2> 531783 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.Server Started @535913ms
[junit4] 2> 531783 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=64593, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores}
[junit4] 2> 531783 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 531783 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control'
[junit4] 2> 531783 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 531784 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 531784 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\solr.xml
[junit4] 2> 531793 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores
[junit4] 2> 531794 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer New CoreContainer 393088613
[junit4] 2> 531794 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control]
[junit4] 2> 531794 WARN (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\lib
[junit4] 2> 531804 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] 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> 531809 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 531810 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 531810 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 531811 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 531811 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 531812 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores
[junit4] 2> 531814 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\, configSet=collection1}
[junit4] 2> 531814 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1
[junit4] 2> 531815 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 531815 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0
[junit4] 2> 531815 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 531815 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1'
[junit4] 2> 531817 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 531818 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 531818 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 531818 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 531890 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 531900 WARN (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 531903 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 531927 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 531935 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 532010 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 532019 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 532019 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 532025 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 532029 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 532033 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 532033 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 532045 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1'
[junit4] 2> 532045 INFO (coreLoadExecutor-1233-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 532046 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 532046 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1\data\]
[junit4] 2> 532046 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@51b61748
[junit4] 2> 532048 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1\data\
[junit4] 2> 532048 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1\data\index/
[junit4] 2> 532049 WARN (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 532049 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1\data\index
[junit4] 2> 532050 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=918341994, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 532051 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@767f4546 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6bbbbec7),segFN=segments_1,generation=1}
[junit4] 2> 532051 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 532055 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 532056 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 532056 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 532057 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1\data\
[junit4] 2> 532060 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\control\cores\collection1\data\ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 532064 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 532066 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 532068 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 532070 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 532074 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 532075 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 532076 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 532077 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 532077 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 532079 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 532079 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 532079 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 532079 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@767f4546 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6bbbbec7),segFN=segments_1,generation=1}
[junit4] 2> 532079 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 532080 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@67457ba8[collection1] main]
[junit4] 2> 532080 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 532080 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 532080 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 532080 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 532173 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 532352 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 532353 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 532353 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 532354 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 532354 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 532354 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 532354 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 532354 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1529342710170779648
[junit4] 2> 532354 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 532355 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 532355 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 532356 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 532356 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1529342710170779648
[junit4] 2> 532356 INFO (coreLoadExecutor-1233-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 532357 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 532360 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 532361 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@67457ba8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 533334 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 533338 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 533340 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5ef754e1{/,null,AVAILABLE}
[junit4] 2> 533341 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7713a231{HTTP/1.1,[http/1.1]}{127.0.0.1:64596}
[junit4] 2> 533342 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.Server Started @537472ms
[junit4] 2> 533342 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=64596, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores}
[junit4] 2> 533342 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 533343 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0'
[junit4] 2> 533343 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 533343 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 533344 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\solr.xml
[junit4] 2> 533359 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores
[junit4] 2> 533359 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer New CoreContainer 1632751172
[junit4] 2> 533359 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0]
[junit4] 2> 533359 WARN (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\lib
[junit4] 2> 533359 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] 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> 533364 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 533364 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 533364 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 533364 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 533364 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 533364 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores
[junit4] 2> 533367 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\, configSet=collection1}
[junit4] 2> 533367 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 533368 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 533368 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0
[junit4] 2> 533368 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 533369 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1'
[junit4] 2> 533370 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 533370 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 533370 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 533370 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 533436 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 533441 WARN (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 533441 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 533456 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 533461 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 533609 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 533617 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 533617 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 533626 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 533629 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 533633 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 533634 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 533646 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1'
[junit4] 2> 533646 INFO (coreLoadExecutor-1244-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 533646 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 533647 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1\data\]
[junit4] 2> 533647 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@51b61748
[junit4] 2> 533647 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1\data\
[junit4] 2> 533648 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1\data\index/
[junit4] 2> 533648 WARN (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 533648 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1\data\index
[junit4] 2> 533648 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=918341994, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 533652 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@440b8c81 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c809a3a),segFN=segments_1,generation=1}
[junit4] 2> 533652 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 533653 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1\data\
[junit4] 2> 533655 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.DirectoryFactory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard0\cores\collection1\data\ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 533660 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 533661 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 533661 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 533661 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 533661 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 533661 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 533661 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 533661 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 533661 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 533662 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 533662 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 533662 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 533668 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 533670 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 533671 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 533672 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 533682 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 533685 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,/export,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/sql,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 533685 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 533687 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 533687 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 533688 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 533688 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 533689 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 533691 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@440b8c81 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c809a3a),segFN=segments_1,generation=1}
[junit4] 2> 533691 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 533691 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6717481a[collection1] main]
[junit4] 2> 533691 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 533691 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 533691 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 533691 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 533778 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 533986 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 533986 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 533987 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 533987 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 533987 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 533987 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1529342711883104256
[junit4] 2> 533987 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 533987 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 533987 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 533988 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 533990 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1529342711883104256
[junit4] 2> 533990 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 533990 INFO (coreLoadExecutor-1244-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 533990 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 533991 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 533992 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 533993 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6717481a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 534374 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 534388 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1529342712295194624)} 0 8
[junit4] 2> 534400 INFO (qtp1609596982-2540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1529342712309874688)} 0 5
[junit4] 2> 534409 INFO (qtp1524252998-2525) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1529342712319311872)]} 0 5
[junit4] 2> 534417 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1529342712328749056)]} 0 5
[junit4] 2> 534422 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1529342712336089088)]} 0 2
[junit4] 2> 534433 INFO (qtp1609596982-2541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1529342712346574848)]} 0 3
[junit4] 2> 534437 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1529342712351817728)]} 0 2
[junit4] 2> 534440 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1529342712357060608)]} 0 1
[junit4] 2> 534444 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1529342712360206336)]} 0 1
[junit4] 2> 534445 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1529342712363352064)]} 0 0
[junit4] 2> 534449 INFO (qtp1524252998-2521) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1529342712365449216)]} 0 1
[junit4] 2> 534451 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1529342712368594944)]} 0 0
[junit4] 2> 534453 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1529342712370692096)]} 0 1
[junit4] 2> 534455 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1529342712372789248)]} 0 1
[junit4] 2> 534459 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1529342712376983552)]} 0 1
[junit4] 2> 534462 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1529342712380129280)]} 0 0
[junit4] 2> 534465 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1529342712383275008)]} 0 1
[junit4] 2> 534467 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1529342712385372160)]} 0 0
[junit4] 2> 534469 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1529342712387469312)]} 0 0
[junit4] 2> 534472 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1529342712390615040)]} 0 1
[junit4] 2> 534475 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 534485 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@767f4546 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6bbbbec7),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@767f4546 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6bbbbec7),segFN=segments_2,generation=2}
[junit4] 2> 534486 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 534489 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ba526cb[collection1] main]
[junit4] 2> 534489 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 534490 INFO (searcherExecutor-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ba526cb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
[junit4] 2> 534490 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 15
[junit4] 2> 534492 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 534501 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@440b8c81 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c809a3a),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@440b8c81 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c809a3a),segFN=segments_2,generation=2}
[junit4] 2> 534501 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 534503 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@49ce821a[collection1] main]
[junit4] 2> 534504 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 534505 INFO (searcherExecutor-1245-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@49ce821a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
[junit4] 2> 534505 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 12
[junit4] 2> 534514 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1529342712429412352)]} 0 5
[junit4] 2> 534521 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1529342712437800960)]} 0 4
[junit4] 2> 534523 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1529342712444092416)]} 0 0
[junit4] 2> 534526 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1529342712447238144)]} 0 0
[junit4] 2> 534532 INFO (qtp1524252998-2521) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1529342712449335296)]} 0 5
[junit4] 2> 534538 INFO (qtp1609596982-2540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1529342712457723904)]} 0 2
[junit4] 2> 534541 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1529342712462966784)]} 0 0
[junit4] 2> 534542 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1529342712465063936)]} 0 0
[junit4] 2> 534545 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1529342712468209664)]} 0 0
[junit4] 2> 534547 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1529342712469258240)]} 0 1
[junit4] 2> 534551 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1529342712473452544)]} 0 0
[junit4] 2> 534552 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1529342712475549696)]} 0 0
[junit4] 2> 534557 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1529342712478695424)]} 0 0
[junit4] 2> 534558 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1529342712481841152)]} 0 0
[junit4] 2> 534562 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1529342712484986880)]} 0 0
[junit4] 2> 534564 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1529342712487084032)]} 0 0
[junit4] 2> 534568 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1529342712490229760)]} 0 1
[junit4] 2> 534570 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1529342712494424064)]} 0 0
[junit4] 2> 534572 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1529342712496521216)]} 0 0
[junit4] 2> 534574 INFO (qtp1609596982-2540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1529342712498618368)]} 0 0
[junit4] 2> 534577 INFO (qtp1524252998-2521) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1529342712500715520)]} 0 0
[junit4] 2> 534578 INFO (qtp1609596982-2541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1529342712502812672)]} 0 0
[junit4] 2> 534580 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1529342712503861248)]} 0 0
[junit4] 2> 534581 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1529342712505958400)]} 0 0
[junit4] 2> 534583 INFO (qtp1524252998-2525) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1529342712508055552)]} 0 0
[junit4] 2> 534584 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1529342712509104128)]} 0 0
[junit4] 2> 534587 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1529342712511201280)]} 0 0
[junit4] 2> 534588 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1529342712513298432)]} 0 0
[junit4] 2> 534590 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1529342712515395584)]} 0 0
[junit4] 2> 534592 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1529342712517492736)]} 0 0
[junit4] 2> 534594 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1529342712519589888)]} 0 0
[junit4] 2> 534595 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1529342712520638464)]} 0 0
[junit4] 2> 534598 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1529342712523784192)]} 0 0
[junit4] 2> 534601 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1529342712526929920)]} 0 0
[junit4] 2> 534603 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1529342712529027072)]} 0 0
[junit4] 2> 534606 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1529342712532172800)]} 0 0
[junit4] 2> 534610 INFO (qtp1524252998-2521) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1529342712535318528)]} 0 0
[junit4] 2> 534612 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1529342712538464256)]} 0 0
[junit4] 2> 534615 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1529342712540561408)]} 0 0
[junit4] 2> 534617 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1529342712543707136)]} 0 0
[junit4] 2> 534619 INFO (qtp1524252998-2525) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1529342712545804288)]} 0 0
[junit4] 2> 534623 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1529342712549998592)]} 0 0
[junit4] 2> 534625 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1529342712552095744)]} 0 0
[junit4] 2> 534626 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1529342712553144320)]} 0 0
[junit4] 2> 534628 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1529342712555241472)]} 0 0
[junit4] 2> 534630 INFO (qtp1609596982-2540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1529342712557338624)]} 0 0
[junit4] 2> 534633 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1529342712560484352)]} 0 0
[junit4] 2> 534635 INFO (qtp1609596982-2541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1529342712562581504)]} 0 0
[junit4] 2> 534637 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1529342712564678656)]} 0 0
[junit4] 2> 534640 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1529342712567824384)]} 0 0
[junit4] 2> 534644 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1529342712570970112)]} 0 0
[junit4] 2> 534645 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1529342712573067264)]} 0 0
[junit4] 2> 534649 INFO (qtp1524252998-2521) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1529342712576212992)]} 0 0
[junit4] 2> 534652 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1529342712580407296)]} 0 1
[junit4] 2> 534655 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1529342712582504448)]} 0 0
[junit4] 2> 534657 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1529342712585650176)]} 0 0
[junit4] 2> 534660 INFO (qtp1524252998-2525) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1529342712588795904)]} 0 0
[junit4] 2> 534662 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1529342712590893056)]} 0 0
[junit4] 2> 534664 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1529342712592990208)]} 0 0
[junit4] 2> 534667 INFO (qtp1609596982-2540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1529342712596135936)]} 0 0
[junit4] 2> 534669 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1529342712598233088)]} 0 0
[junit4] 2> 534672 INFO (qtp1609596982-2541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1529342712600330240)]} 0 0
[junit4] 2> 534673 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1529342712602427392)]} 0 0
[junit4] 2> 534677 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1529342712605573120)]} 0 0
[junit4] 2> 534679 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1529342712607670272)]} 0 0
[junit4] 2> 534682 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1529342712611864576)]} 0 0
[junit4] 2> 534685 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1529342712615010304)]} 0 0
[junit4] 2> 534688 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1529342712617107456)]} 0 0
[junit4] 2> 534690 INFO (qtp1524252998-2521) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1529342712620253184)]} 0 0
[junit4] 2> 534694 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1529342712623398912)]} 0 0
[junit4] 2> 534697 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1529342712626544640)]} 0 0
[junit4] 2> 534698 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1529342712628641792)]} 0 0
[junit4] 2> 534703 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1529342712632836096)]} 0 0
[junit4] 2> 534705 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1529342712634933248)]} 0 0
[junit4] 2> 534708 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1529342712638078976)]} 0 0
[junit4] 2> 534712 INFO (qtp1609596982-2541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1529342712641224704)]} 0 1
[junit4] 2> 534715 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1529342712645419008)]} 0 0
[junit4] 2> 534717 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1529342712647516160)]} 0 0
[junit4] 2> 534718 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1529342712649613312)]} 0 0
[junit4] 2> 534721 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1529342712652759040)]} 0 0
[junit4] 2> 534724 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1529342712654856192)]} 0 1
[junit4] 2> 534726 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1529342712656953344)]} 0 0
[junit4] 2> 534727 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1529342712659050496)]} 0 0
[junit4] 2> 534730 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1529342712662196224)]} 0 0
[junit4] 2> 534732 INFO (qtp1524252998-2521) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1529342712664293376)]} 0 0
[junit4] 2> 534734 INFO (qtp1609596982-2540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1529342712665341952)]} 0 0
[junit4] 2> 534736 INFO (qtp1524252998-2525) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1529342712667439104)]} 0 0
[junit4] 2> 534738 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1529342712670584832)]} 0 0
[junit4] 2> 534740 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1529342712672681984)]} 0 0
[junit4] 2> 534743 INFO (qtp1609596982-2541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1529342712674779136)]} 0 0
[junit4] 2> 534744 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1529342712676876288)]} 0 0
[junit4] 2> 534748 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1529342712680022016)]} 0 1
[junit4] 2> 534749 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1529342712682119168)]} 0 0
[junit4] 2> 534751 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1529342712684216320)]} 0 0
[junit4] 2> 534754 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1529342712686313472)]} 0 0
[junit4] 2> 534755 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1529342712688410624)]} 0 0
[junit4] 2> 534757 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1529342712690507776)]} 0 0
[junit4] 2> 534760 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1529342712692604928)]} 0 1
[junit4] 2> 534762 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1529342712695750656)]} 0 0
[junit4] 2> 534763 INFO (qtp1609596982-2540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1529342712696799232)]} 0 0
[junit4] 2> 534765 INFO (qtp1524252998-2521) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1529342712698896384)]} 0 0
[junit4] 2> 534766 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1529342712699944960)]} 0 0
[junit4] 2> 534768 INFO (qtp1524252998-2525) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1529342712702042112)]} 0 0
[junit4] 2> 534770 INFO (qtp1609596982-2541) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1529342712704139264)]} 0 0
[junit4] 2> 534773 INFO (qtp1524252998-2530) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1529342712707284992)]} 0 0
[junit4] 2> 534776 INFO (qtp1609596982-2542) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1529342712709382144)]} 0 0
[junit4] 2> 534778 INFO (qtp1524252998-2520) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1529342712712527872)]} 0 0
[junit4] 2> 534780 INFO (qtp1609596982-2543) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1529342712714625024)]} 0 0
[junit4] 2> 534784 INFO (qtp1524252998-2526) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1529342712718819328)]} 0 0
[junit4] 2> 534788 INFO (qtp1609596982-2538) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1529342712721965056)]} 0 0
[junit4] 2> 534789 INFO (qtp1524252998-2524) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1529342712724062208)]} 0 0
[junit4] 2> 534791 INFO (qtp1609596982-2544) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1529342712726159360)]} 0 0
[junit4] 2> 534794 INFO (qtp1524252998-2523) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1529342712729305088)]} 0 0
[junit4] 2> 534797 INFO (qtp1609596982-2540) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1529342712732450816)]} 0 0
[junit4] 2> 534800 INFO (qtp1524252998-2522) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1529342712734547968)]} 0 0
[junit4] 2> 534802 INFO (qtp1609596982-2545) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (15293427127366451
[...truncated too long message...]
ndows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard2\cores\collection1\data\index;done=false>>]
[junit4] 2> 668054 INFO (coreCloseExecutor-1343-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard2\cores\collection1\data\index
[junit4] 2> 668055 INFO (coreCloseExecutor-1343-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard2\cores\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard2\cores\collection1\data\;done=false>>]
[junit4] 2> 668055 INFO (coreCloseExecutor-1343-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001\tempDir-001\shard2\cores\collection1\data\
[junit4] 2> 668058 INFO (TEST-TestDistributedSearch.test-seed#[34C2AABA11A78881]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7ccf19af{/,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=34C2AABA11A78881 -Dtests.slow=true -Dtests.locale=ja -Dtests.timezone=Antarctica/DumontDUrville -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 137s J0 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:64900//collection1: java.lang.NullPointerException
[junit4] > at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:105)
[junit4] > at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:753)
[junit4] > at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:736)
[junit4] > at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:420)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2033)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:652)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:229)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:184)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[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:1676)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] > at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] > at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] > at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([34C2AABA11A78881:BC969560BF5BE579]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
[junit4] > at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1137)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1099)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:961)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1022)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 668064 INFO (SUITE-TestDistributedSearch-seed#[34C2AABA11A78881]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_34C2AABA11A78881-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=ClassicSimilarity, locale=ja, timezone=Antarctica/DumontDUrville
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_72 (64-bit)/cpus=3,threads=2,free=97825352,total=329252864
[junit4] 2> NOTE: All tests run in this JVM: [HardAutoCommitTest, ShardSplitTest, ConnectionManagerTest, TestArbitraryIndexDir, TestSizeLimitedDistributedMap, TestFieldTypeCollectionResource, TestXIncludeConfig, SolrRequestParserTest, TestTolerantSearch, DistributedQueryElevationComponentTest, TestLRUCache, DocExpirationUpdateProcessorFactoryTest, TestSolrQueryParserResource, ConcurrentDeleteAndCreateCollectionTest, FacetPivotSmallTest, OverseerTaskQueueTest, TestConfigSets, BlockJoinFacetRandomTest, IndexBasedSpellCheckerTest, TestSmileRequest, TestUseDocValuesAsStored2, TestSearchPerf, AsyncMigrateRouteKeyTest, AddBlockUpdateTest, AlternateDirectoryTest, DisMaxRequestHandlerTest, SparseHLLTest, TestPivotHelperCode, TermsComponentTest, TestAnalyzeInfixSuggestions, TestRandomRequestDistribution, ExternalFileFieldSortTest, TestReplicaProperties, HdfsChaosMonkeySafeLeaderTest, HdfsTlogReplayBufferedWhileIndexingTest, PreAnalyzedFieldTest, TestNoOpRegenerator, CollectionStateFormat2Test, TestDocBasedVersionConstraints, TestSolrDeletionPolicy1, TestQuerySenderNoQuery, RecoveryZkTest, TlogReplayBufferedWhileIndexingTest, TestPartialUpdateDeduplication, TestDistributedSearch]
[junit4] Completed [107/580 (1!)] on J0 in 137.12s, 1 test, 1 error <<< FAILURES!
[...truncated 1548 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:740: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:684: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build.xml:233: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\common-build.xml:529: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1457: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1014: There were test failures: 580 suites (10 ignored), 2320 tests, 1 error, 124 ignored (79 assumptions) [seed: 34C2AABA11A78881]
Total time: 75 minutes 14 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