Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2380/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.TestDistributedSearch.test Error Message: Error from server at http://127.0.0.1:52224//collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:388) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2064) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:634) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:438) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:227) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:196) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:106) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) at org.eclipse.jetty.server.Server.handle(Server.java:497) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) at java.lang.Thread.run(Thread.java:745) Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:52224//collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:388) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2064) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:634) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:438) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:227) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:196) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:106) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) at org.eclipse.jetty.server.Server.handle(Server.java:497) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) at java.lang.Thread.run(Thread.java:745) at __randomizedtesting.SeedInfo.seed([496726ECC529E1C1:C13319366BD58C39]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958) at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133) at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095) at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9882 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/init-core-data-001 [junit4] 2> 963553 INFO (SUITE-TestDistributedSearch-seed#[496726ECC529E1C1]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 963554 INFO (SUITE-TestDistributedSearch-seed#[496726ECC529E1C1]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 964145 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores/collection1 [junit4] 2> 964149 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.e.j.s.Server jetty-9.2.10.v20150310 [junit4] 2> 964155 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f6f98e3{/,null,AVAILABLE} [junit4] 2> 964180 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1c2213ff{HTTP/1.1}{127.0.0.1:52147} [junit4] 2> 964181 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.e.j.s.Server Started @969788ms [junit4] 2> 964181 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=52147, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores} [junit4] 2> 964181 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482 [junit4] 2> 964181 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/' [junit4] 2> 964232 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/solr.xml [junit4] 2> 964248 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores [junit4] 2> 964248 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoreContainer New CoreContainer 1783021770 [junit4] 2> 964248 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/] [junit4] 2> 964249 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoreContainer loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/lib [junit4] 2> 964249 WARN (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/lib). [junit4] 2> 964276 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] 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> 964282 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 964283 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 964283 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 964283 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 964288 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores [junit4] 2> 964290 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 964290 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoresLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores/collection1/ [junit4] 2> 964290 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 964291 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 964292 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 964294 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 964295 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 964295 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 964350 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 964359 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 964393 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 964394 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 964399 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 964591 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 964608 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 964610 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 964618 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 964622 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 964625 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 964626 WARN (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 964626 WARN (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 964626 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 964627 WARN (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 964627 WARN (coreLoadExecutor-2822-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 964628 INFO (coreLoadExecutor-2822-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1 [junit4] 2> 964628 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 964629 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/], dataDir=[null] [junit4] 2> 964629 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@666d105a [junit4] 2> 964629 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores/collection1/data [junit4] 2> 964629 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores/collection1/data/index/ [junit4] 2> 964630 WARN (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 964630 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 964630 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 964631 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e60da59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@532f3ed6),segFN=segments_1,generation=1} [junit4] 2> 964631 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 964639 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 964640 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 964640 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 964640 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 964641 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 964641 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 964641 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 964641 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 964641 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 964642 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 964642 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 964642 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 964654 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 964657 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 964658 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 964664 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 964675 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 964679 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 964680 WARN (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 964681 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 964694 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 964695 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 964718 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 964718 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 964718 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 964718 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e60da59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@532f3ed6),segFN=segments_1,generation=1} [junit4] 2> 964719 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 964719 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@5c1bc02d[collection1] main [junit4] 2> 964720 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 964720 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage [junit4] 2> 964720 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 964720 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 964731 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 964818 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 964824 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 964826 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 964826 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 964826 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 964828 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 964830 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 964831 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 964833 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 964834 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 964835 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5c1bc02d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 964852 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 964852 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 964853 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 964853 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1502989818314883072 [junit4] 2> 964855 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1502989818314883072 [junit4] 2> 964855 INFO (coreLoadExecutor-2822-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 965911 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 965915 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.e.j.s.Server jetty-9.2.10.v20150310 [junit4] 2> 965919 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@36900a17{/,null,AVAILABLE} [junit4] 2> 965919 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4b80055a{HTTP/1.1}{127.0.0.1:52152} [junit4] 2> 965920 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.e.j.s.Server Started @971526ms [junit4] 2> 965920 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=52152, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores} [junit4] 2> 965920 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482 [junit4] 2> 965920 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/' [junit4] 2> 965964 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/solr.xml [junit4] 2> 965975 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores [junit4] 2> 965975 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoreContainer New CoreContainer 136890812 [junit4] 2> 965975 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/] [junit4] 2> 965975 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoreContainer loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/lib [junit4] 2> 965976 WARN (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/lib). [junit4] 2> 965988 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] 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> 965992 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 965993 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 965993 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 965993 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 965994 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores [junit4] 2> 965996 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 965996 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoresLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores/collection1/ [junit4] 2> 965996 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 965997 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 965998 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 965998 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 965999 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 965999 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 966035 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 966044 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 966064 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 966064 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 966068 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 966193 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 966201 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 966202 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 966208 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 966210 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 966212 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 966213 WARN (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 966213 WARN (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 966213 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 966214 WARN (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 966214 WARN (coreLoadExecutor-2833-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 966214 INFO (coreLoadExecutor-2833-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1 [junit4] 2> 966214 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 966214 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/], dataDir=[null] [junit4] 2> 966214 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@666d105a [junit4] 2> 966216 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 966216 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores/collection1/data/index/ [junit4] 2> 966216 WARN (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 966216 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001/tempDir-001/shard0/cores/collection1/data/index [junit4] 2> 966217 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 966217 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b5ee510 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@735cc7ea),segFN=segments_1,generation=1} [junit4] 2> 966217 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 966222 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 966223 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 966223 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 966223 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 966223 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 966223 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 966223 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 966223 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 966224 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 966224 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 966224 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 966224 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 966231 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 966232 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 966233 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 966235 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 966243 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 966246 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 966247 WARN (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 966247 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 966249 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 966249 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 966250 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 966250 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 966252 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 966252 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b5ee510 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@735cc7ea),segFN=segments_1,generation=1} [junit4] 2> 966252 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 966252 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2c10d0ed[collection1] main [junit4] 2> 966252 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 966252 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage [junit4] 2> 966252 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 966253 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 966260 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 966295 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 966295 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 966296 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 966296 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 966296 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 966296 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 966297 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 966296 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 966297 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1502989819829026816 [junit4] 2> 966297 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 966298 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 966298 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 966299 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 966299 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 3 ms to seed version buckets with highest version 1502989819829026816 [junit4] 2> 966299 INFO (coreLoadExecutor-2833-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 966300 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 966301 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2c10d0ed[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 966500 INFO (TEST-TestDistributedSearch.test-seed#[496726ECC529E1C1]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 966518 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1502989820057616384)} 0 3 [junit4] 2> 966526 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1502989820066004992)} 0 3 [junit4] 2> 966530 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1502989820072296448)]} 0 1 [junit4] 2> 966535 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1502989820076490752)]} 0 1 [junit4] 2> 966539 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1502989820081733632)]} 0 2 [junit4] 2> 966543 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1502989820085927936)]} 0 1 [junit4] 2> 966546 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1502989820090122240)]} 0 0 [junit4] 2> 966550 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1502989820093267968)]} 0 1 [junit4] 2> 966555 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1502989820098510848)]} 0 1 [junit4] 2> 966560 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1502989820102705152)]} 0 2 [junit4] 2> 966564 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1502989820108996608)]} 0 1 [junit4] 2> 966568 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1502989820112142336)]} 0 1 [junit4] 2> 966572 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1502989820116336640)]} 0 1 [junit4] 2> 966575 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1502989820120530944)]} 0 1 [junit4] 2> 966580 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1502989820124725248)]} 0 1 [junit4] 2> 966584 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1502989820129968128)]} 0 0 [junit4] 2> 966588 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1502989820134162432)]} 0 0 [junit4] 2> 966592 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1502989820138356736)]} 0 1 [junit4] 2> 966596 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1502989820141502464)]} 0 1 [junit4] 2> 966600 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1502989820145696768)]} 0 1 [junit4] 2> 966603 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 966618 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e60da59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@532f3ed6),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e60da59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@532f3ed6),segFN=segments_2,generation=2} [junit4] 2> 966620 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2 [junit4] 2> 966622 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4e399a21[collection1] main [junit4] 2> 966622 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 966623 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e399a21[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9)))} [junit4] 2> 966624 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 22 [junit4] 2> 966627 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 966670 INFO (qtp532158227-6374) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b5ee510 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@735cc7ea),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b5ee510 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@735cc7ea),segFN=segments_2,generation=2} [junit4] 2> 966671 INFO (qtp532158227-6374) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2 [junit4] 2> 966673 INFO (qtp532158227-6374) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3ee07f54[collection1] main [junit4] 2> 966674 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 966677 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3ee07f54[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9)))} [junit4] 2> 966678 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 51 [junit4] 2> 966688 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1502989820234825728)]} 0 5 [junit4] 2> 966695 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1502989820242165760)]} 0 4 [junit4] 2> 966700 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1502989820249505792)]} 0 2 [junit4] 2> 966703 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1502989820254748672)]} 0 1 [junit4] 2> 966710 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1502989820259991552)]} 0 2 [junit4] 2> 966716 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1502989820266283008)]} 0 2 [junit4] 2> 966722 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1502989820273623040)]} 0 1 [junit4] 2> 966726 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1502989820277817344)]} 0 1 [junit4] 2> 966731 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1502989820282011648)]} 0 2 [junit4] 2> 966735 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1502989820287254528)]} 0 1 [junit4] 2> 966739 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1502989820291448832)]} 0 1 [junit4] 2> 966742 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1502989820294594560)]} 0 1 [junit4] 2> 966746 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1502989820299837440)]} 0 1 [junit4] 2> 966753 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1502989820306128896)]} 0 1 [junit4] 2> 966758 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1502989820311371776)]} 0 1 [junit4] 2> 966767 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1502989820320808960)]} 0 2 [junit4] 2> 966770 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1502989820325003264)]} 0 0 [junit4] 2> 966773 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1502989820328148992)]} 0 0 [junit4] 2> 966777 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1502989820331294720)]} 0 1 [junit4] 2> 966780 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1502989820334440448)]} 0 1 [junit4] 2> 966783 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1502989820338634752)]} 0 1 [junit4] 2> 966793 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1502989820344926208)]} 0 4 [junit4] 2> 966798 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1502989820353314816)]} 0 1 [junit4] 2> 966803 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1502989820358557696)]} 0 1 [junit4] 2> 966807 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1502989820362752000)]} 0 1 [junit4] 2> 966810 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1502989820366946304)]} 0 1 [junit4] 2> 966814 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1502989820371140608)]} 0 0 [junit4] 2> 966820 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1502989820375334912)]} 0 3 [junit4] 2> 966825 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1502989820381626368)]} 0 1 [junit4] 2> 966827 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1502989820384772096)]} 0 0 [junit4] 2> 966831 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1502989820388966400)]} 0 0 [junit4] 2> 966835 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1502989820392112128)]} 0 1 [junit4] 2> 966839 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1502989820396306432)]} 0 1 [junit4] 2> 966850 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1502989820404695040)]} 0 5 [junit4] 2> 966854 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1502989820412035072)]} 0 1 [junit4] 2> 966857 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1502989820416229376)]} 0 1 [junit4] 2> 966862 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1502989820421472256)]} 0 0 [junit4] 2> 966866 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1502989820424617984)]} 0 1 [junit4] 2> 966870 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1502989820429860864)]} 0 1 [junit4] 2> 966873 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1502989820433006592)]} 0 0 [junit4] 2> 966877 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1502989820436152320)]} 0 1 [junit4] 2> 966880 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1502989820440346624)]} 0 1 [junit4] 2> 966884 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1502989820444540928)]} 0 0 [junit4] 2> 966889 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1502989820448735232)]} 0 2 [junit4] 2> 966898 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1502989820457123840)]} 0 4 [junit4] 2> 966907 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1502989820468658176)]} 0 0 [junit4] 2> 966911 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1502989820472852480)]} 0 1 [junit4] 2> 966915 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1502989820475998208)]} 0 1 [junit4] 2> 966919 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1502989820480192512)]} 0 2 [junit4] 2> 966922 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1502989820483338240)]} 0 1 [junit4] 2> 966925 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1502989820487532544)]} 0 1 [junit4] 2> 966928 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1502989820490678272)]} 0 0 [junit4] 2> 966932 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1502989820493824000)]} 0 1 [junit4] 2> 966935 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1502989820498018304)]} 0 0 [junit4] 2> 966940 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1502989820502212608)]} 0 1 [junit4] 2> 966944 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1502989820506406912)]} 0 1 [junit4] 2> 966948 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1502989820510601216)]} 0 1 [junit4] 2> 966951 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1502989820514795520)]} 0 0 [junit4] 2> 966955 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1502989820517941248)]} 0 1 [junit4] 2> 966958 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1502989820521086976)]} 0 1 [junit4] 2> 966961 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1502989820525281280)]} 0 0 [junit4] 2> 966965 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1502989820529475584)]} 0 0 [junit4] 2> 966969 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1502989820532621312)]} 0 1 [junit4] 2> 966972 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1502989820536815616)]} 0 0 [junit4] 2> 966975 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1502989820539961344)]} 0 0 [junit4] 2> 966980 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1502989820545204224)]} 0 0 [junit4] 2> 966984 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1502989820548349952)]} 0 1 [junit4] 2> 966987 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1502989820552544256)]} 0 0 [junit4] 2> 966990 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1502989820554641408)]} 0 1 [junit4] 2> 966994 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1502989820559884288)]} 0 0 [junit4] 2> 966997 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1502989820563030016)]} 0 0 [junit4] 2> 967001 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1502989820566175744)]} 0 1 [junit4] 2> 967004 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1502989820570370048)]} 0 0 [junit4] 2> 967008 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1502989820573515776)]} 0 1 [junit4] 2> 967013 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1502989820577710080)]} 0 2 [junit4] 2> 967017 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1502989820584001536)]} 0 0 [junit4] 2> 967023 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1502989820589244416)]} 0 1 [junit4] 2> 967029 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1502989820595535872)]} 0 2 [junit4] 2> 967035 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1502989820600778752)]} 0 2 [junit4] 2> 967040 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1502989820608118784)]} 0 0 [junit4] 2> 967043 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1502989820611264512)]} 0 0 [junit4] 2> 967046 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1502989820614410240)]} 0 0 [junit4] 2> 967050 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1502989820617555968)]} 0 1 [junit4] 2> 967053 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1502989820621750272)]} 0 0 [junit4] 2> 967056 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1502989820624896000)]} 0 0 [junit4] 2> 967059 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1502989820628041728)]} 0 0 [junit4] 2> 967063 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1502989820632236032)]} 0 0 [junit4] 2> 967066 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1502989820635381760)]} 0 0 [junit4] 2> 967069 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1502989820638527488)]} 0 0 [junit4] 2> 967073 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1502989820642721792)]} 0 0 [junit4] 2> 967078 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1502989820647964672)]} 0 1 [junit4] 2> 967083 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1502989820652158976)]} 0 1 [junit4] 2> 967086 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1502989820656353280)]} 0 0 [junit4] 2> 967090 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1502989820660547584)]} 0 0 [junit4] 2> 967096 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1502989820665790464)]} 0 1 [junit4] 2> 967099 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1502989820669984768)]} 0 0 [junit4] 2> 967103 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1502989820674179072)]} 0 0 [junit4] 2> 967107 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1502989820678373376)]} 0 0 [junit4] 2> 967110 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1502989820680470528)]} 0 1 [junit4] 2> 967115 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1502989820684664832)]} 0 2 [junit4] 2> 967120 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1502989820690956288)]} 0 1 [junit4] 2> 967123 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1502989820695150592)]} 0 0 [junit4] 2> 967130 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1502989820698296320)]} 0 3 [junit4] 2> 967134 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1502989820705636352)]} 0 1 [junit4] 2> 967138 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1502989820710879232)]} 0 0 [junit4] 2> 967143 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1502989820715073536)]} 0 0 [junit4] 2> 967153 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1502989820724510720)]} 0 2 [junit4] 2> 967158 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1502989820731850752)]} 0 1 [junit4] 2> 967163 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1502989820736045056)]} 0 1 [junit4] 2> 967166 INFO (qtp532158227-6375) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1502989820739190784)]} 0 1 [junit4] 2> 967169 INFO (qtp1524723217-6353) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1502989820743385088)]} 0 0 [junit4] 2> 967174 INFO (qtp532158227-6370) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1502989820747579392)]} 0 1 [junit4] 2> 967177 INFO (qtp1524723217-6354) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1502989820751773696)]} 0 0 [junit4] 2> 967183 INFO (qtp532158227-6371) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1502989820758065152)]} 0 0 [junit4] 2> 967187 INFO (qtp1524723217-6355) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1502989820762259456)]} 0 0 [junit4] 2> 967191 INFO (qtp532158227-6372) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1502989820766453760)]} 0 0 [junit4] 2> 967196 INFO (qtp1524723217-6356) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1502989820770648064)]} 0 1 [junit4] 2> 967200 INFO (qtp532158227-6373) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1502989820775890944)]} 0 0 [junit4] 2> 967203 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 967224 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e60da59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@532f3ed6),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e60da59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@532f3ed6),segFN=segments_3,generation=3} [junit4] 2> 967224 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 3 [junit4] 2> 967225 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@66858490[collection1] main [junit4] 2> 967226 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 967228 INFO (searcherExecutor-2823-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@66858490[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9) Uninverting(_1(6.0.0):c59)))} [junit4] 2> 967229 INFO (qtp1524723217-6357) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 26 [junit4] 2> 967233 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 967267 INFO (qtp532158227-6374) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b5ee510 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@735cc7ea),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b5ee510 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@735cc7ea),segFN=segments_3,generation=3} [junit4] 2> 967267 INFO (qtp532158227-6374) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 3 [junit4] 2> 967268 INFO (qtp532158227-6374) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3c7eb49[collection1] main [junit4] 2> 967268 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 967270 INFO (searcherExecutor-2834-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3c7eb49[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9) Uninverting(_1(6.0.0):c59)))} [junit4] 2> 967270 INFO (qtp532158227-6374) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 37 [junit4] 2> 967277 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.c.SolrCore Ignoring exception matching parameter cannot be negative [junit4] 2> 967277 INFO (qtp1524723217-6358) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=1 [juni [...truncated too long message...] ibutedSearch.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:52224//collection1: java.lang.NullPointerException [junit4] > at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102) [junit4] > at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744) [junit4] > at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727) [junit4] > at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:388) [junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) [junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2064) [junit4] > at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:634) [junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:438) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:227) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:196) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:106) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] > at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) [junit4] > at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) [junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) [junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) [junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) [junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) [junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) [junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:497) [junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) [junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) [junit4] > at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > at __randomizedtesting.SeedInfo.seed([496726ECC529E1C1:C13319366BD58C39]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958) [junit4] > at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133) [junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095) [junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1091998 INFO (SUITE-TestDistributedSearch-seed#[496726ECC529E1C1]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 496726ECC529E1C1-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=PostingsFormat(name=LuceneFixedGap), range_facet_l_dv=FST50, SubjectTerms_mfacet=PostingsFormat(name=LuceneFixedGap), n_l1=PostingsFormat(name=LuceneFixedGap), a_n_tdt=FST50, multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), intDefault=PostingsFormat(name=LuceneFixedGap), n_td1=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_d1=PostingsFormat(name=LuceneFixedGap), n_f1=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_l=PostingsFormat(name=LuceneFixedGap), n_tl1=PostingsFormat(name=LuceneVarGapDocFreqInterval), oddField_s=PostingsFormat(name=LuceneVarGapFixedInterval), n_tf1=PostingsFormat(name=LuceneFixedGap), lowerfilt1and2=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_t=PostingsFormat(name=LuceneFixedGap), lowerfilt=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=FST50, text=PostingsFormat(name=LuceneVarGapFixedInterval), timestamp=PostingsFormat(name=LuceneFixedGap), severity=PostingsFormat(name=LuceneVarGapFixedInterval), foo_sev_enum=FST50, n_dt1=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_i1=PostingsFormat(name=LuceneFixedGap), b_n_tdt=PostingsFormat(name=LuceneFixedGap), n_ti1=FST50, rnd_b=FST50, _version_=PostingsFormat(name=LuceneFixedGap), foo_b=PostingsFormat(name=LuceneVarGapFixedInterval), n_tdt1=PostingsFormat(name=LuceneFixedGap), foo_d=FST50, range_facet_i_dv=PostingsFormat(name=LuceneFixedGap), foo_f=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), foo_sev_enum=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ar_IQ, timezone=Africa/Kigali [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=3,threads=1,free=242008344,total=402325504 [junit4] 2> NOTE: All tests run in this JVM: [TestJmxMonitoredMap, ClusterStateUpdateTest, SolrCoreTest, TestNonDefinedSimilarityFactory, SolrCoreCheckLockOnStartupTest, SolrPluginUtilsTest, URLClassifyProcessorTest, TestImplicitCoreProperties, HdfsChaosMonkeySafeLeaderTest, TestCloudSchemaless, ParsingFieldUpdateProcessorsTest, TestSchemaSimilarityResource, SampleTest, OverseerTest, TestStressReorder, CursorMarkTest, TestClusterStateMutator, CachingDirectoryFactoryTest, TestCloudInspectUtil, BadCopyFieldTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestInitQParser, PeerSyncTest, TestWriterPerf, TestSolrConfigHandler, TestSolrConfigHandlerCloud, TestFaceting, TestRandomMergePolicy, TestMiniSolrCloudCluster, HdfsThreadLeakTest, LeaderElectionTest, DocValuesMultiTest, TestExtendedDismaxParser, HttpPartitionTest, DistributedFacetPivotLongTailTest, TestReqParamsAPI, ZkControllerTest, TestPartialUpdateDeduplication, ScriptEngineTest, TriLevelCompositeIdRoutingTest, TestPostingsSolrHighlighter, TestWordDelimiterFilterFactory, TestGroupingSearch, SSLMigrationTest, TestExactStatsCache, RulesTest, RequestLoggingTest, BadComponentTest, NumericFieldsTest, DirectSolrConnectionTest, BasicDistributedZkTest, CoreAdminCreateDiscoverTest, TestNamedUpdateProcessors, QueryResultKeyTest, DirectUpdateHandlerTest, RuleEngineTest, CoreAdminRequestStatusTest, MultiTermTest, SliceStateTest, TestFieldSortValues, CdcrUpdateLogTest, RequiredFieldsTest, TestRandomDVFaceting, DistributedSpellCheckComponentTest, TestJettySolrRunner, ExitableDirectoryReaderTest, TestMacros, TestClassNameShortening, TestShortCircuitedRequests, TestDistributedSearch] [junit4] Completed [163/504] on J0 in 128.48s, 1 test, 1 error <<< FAILURES! [...truncated 1069 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:526: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:474: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:230: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:512: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1418: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:973: There were test failures: 504 suites, 1987 tests, 1 error, 63 ignored (24 assumptions) Total time: 76 minutes 21 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
