You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/04/09 01:03:03 UTC
[JENKINS] Lucene-Solr-5.x-MacOSX (64bit/jdk1.8.0) - Build # 2106 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2106/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Error from server at http://127.0.0.1:60399//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:356) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:1988) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:828) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:102) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235) at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82) at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628) at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) 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:60399//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:356)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1988)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:828)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:102)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([3BA82E6E07E7E727:B3FC11B4A91B8ADF]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:556)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:233)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:225)
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:1055)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1017)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:890)
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:53)
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:53)
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 10441 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/init-core-data-001
[junit4] 2> 1586348 T8536 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1586348 T8536 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1586841 T8536 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1
[junit4] 2> 1586843 T8536 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1586848 T8536 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60315
[junit4] 2> 1586849 T8536 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=60315, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores}
[junit4] 2> 1586850 T8536 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1586850 T8536 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/'
[junit4] 2> 1586881 T8536 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/solr.xml
[junit4] 2> 1586890 T8536 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores
[junit4] 2> 1586890 T8536 oasc.CoreContainer.<init> New CoreContainer 1276060710
[junit4] 2> 1586890 T8536 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/]
[junit4] 2> 1586891 T8536 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/lib
[junit4] 2> 1586892 T8536 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/lib).
[junit4] 2> 1586907 T8536 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1586907 T8536 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1586907 T8536 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1586907 T8536 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1586908 T8536 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1586908 T8536 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1586908 T8536 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1586909 T8536 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1586909 T8536 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1586909 T8536 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1586910 T8536 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1586914 T8536 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1586915 T8536 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1586915 T8536 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1586915 T8536 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1586916 T8536 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores
[junit4] 2> 1586918 T8536 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/}
[junit4] 2> 1586918 T8536 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1/
[junit4] 2> 1586918 T8536 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1586919 T8546 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1586920 T8546 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1586920 T8546 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1586947 T8546 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1586953 T8546 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 1586969 T8546 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1586970 T8546 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 1586974 T8546 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1587053 T8546 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1587060 T8546 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1587061 T8546 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1587067 T8546 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1587069 T8546 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1587080 T8546 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1587080 T8546 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1587080 T8546 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1587080 T8546 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1587081 T8546 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1587081 T8546 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1587081 T8546 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1587081 T8546 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1587081 T8546 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1/data/
[junit4] 2> 1587081 T8546 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@58f86486
[junit4] 2> 1587082 T8546 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1/data
[junit4] 2> 1587082 T8546 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1/data/index/
[junit4] 2> 1587082 T8546 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1587083 T8546 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/control/cores/collection1/data/index
[junit4] 2> 1587083 T8546 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=94.240234375, floorSegmentMB=1.783203125, forceMergeDeletesPctAllowed=15.183514785220568, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1587084 T8546 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7a1b3219 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35516e54),segFN=segments_1,generation=1}
[junit4] 2> 1587084 T8546 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1587088 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1587088 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1587088 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1587088 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1587088 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1587089 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1587089 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1587089 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1587089 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1587090 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1587090 T8546 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1587090 T8546 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1587096 T8546 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1587097 T8546 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1587098 T8546 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1587099 T8546 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1587104 T8546 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1587105 T8546 c:collection1 oasc.RequestHandlers.initHandlersFromConfig 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> 1587105 T8546 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1587106 T8546 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1587107 T8546 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1587107 T8546 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1587108 T8546 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1587108 T8546 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1587108 T8546 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=980192323, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1587109 T8546 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7a1b3219 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35516e54),segFN=segments_1,generation=1}
[junit4] 2> 1587109 T8546 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1587110 T8546 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@3ec76580[collection1] main
[junit4] 2> 1587110 T8546 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1587110 T8546 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1587110 T8546 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1587110 T8546 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1587121 T8546 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1587157 T8546 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1587157 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 1587158 T8546 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1587158 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 1587158 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 1587158 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 1587158 T8536 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1587159 T8536 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1587159 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1587160 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 1587161 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 1587162 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 1587163 T8547 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 1587163 T8547 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ec76580[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1587901 T8536 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1587903 T8536 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1587907 T8536 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60316
[junit4] 2> 1587908 T8536 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=60316, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores}
[junit4] 2> 1587908 T8536 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1587908 T8536 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/'
[junit4] 2> 1587937 T8536 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1587987 T8536 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores
[junit4] 2> 1587988 T8536 oasc.CoreContainer.<init> New CoreContainer 1880443033
[junit4] 2> 1587988 T8536 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/]
[junit4] 2> 1587988 T8536 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/lib
[junit4] 2> 1587989 T8536 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/lib).
[junit4] 2> 1587997 T8536 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1587998 T8536 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1587998 T8536 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1587998 T8536 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1587998 T8536 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1587999 T8536 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1587999 T8536 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1587999 T8536 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1587999 T8536 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1587999 T8536 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1588000 T8536 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1588004 T8536 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1588005 T8536 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1588005 T8536 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1588005 T8536 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1588008 T8536 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores
[junit4] 2> 1588009 T8536 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/}
[junit4] 2> 1588009 T8536 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1/
[junit4] 2> 1588010 T8536 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1588011 T8558 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1588012 T8558 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1588012 T8558 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1588044 T8558 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1588051 T8558 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 1588069 T8558 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1588070 T8558 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 1588074 T8558 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1588143 T8558 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1588151 T8558 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1588152 T8558 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1588158 T8558 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1588159 T8558 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1588176 T8558 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1588177 T8558 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1588177 T8558 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1588177 T8558 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1588178 T8558 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1588178 T8558 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1588178 T8558 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1588178 T8558 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1588178 T8558 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1/data/
[junit4] 2> 1588179 T8558 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@58f86486
[junit4] 2> 1588179 T8558 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1/data
[junit4] 2> 1588179 T8558 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1/data/index/
[junit4] 2> 1588179 T8558 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1588180 T8558 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001/tempDir-001/shard0/cores/collection1/data/index
[junit4] 2> 1588180 T8558 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=94.240234375, floorSegmentMB=1.783203125, forceMergeDeletesPctAllowed=15.183514785220568, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1588180 T8558 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d63a6ab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ccf1d6b),segFN=segments_1,generation=1}
[junit4] 2> 1588180 T8558 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1588186 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1588187 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1588187 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1588187 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1588187 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1588188 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1588188 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1588188 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1588188 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1588189 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1588190 T8558 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1588190 T8558 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1588197 T8558 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1588198 T8558 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1588200 T8558 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1588202 T8558 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1588208 T8558 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1588210 T8558 c:collection1 oasc.RequestHandlers.initHandlersFromConfig 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> 1588210 T8558 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1588211 T8558 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1588212 T8558 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1588212 T8558 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1588213 T8558 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1588213 T8558 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1588215 T8558 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=980192323, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1588215 T8558 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d63a6ab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ccf1d6b),segFN=segments_1,generation=1}
[junit4] 2> 1588215 T8558 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1588215 T8558 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@3b62f716[collection1] main
[junit4] 2> 1588216 T8558 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1588216 T8558 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1588216 T8558 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1588216 T8558 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1588261 T8558 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1588488 T8558 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1588489 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 1588490 T8558 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1588490 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 1588491 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 1588491 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 1588491 T8536 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1588492 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1588492 T8536 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1588493 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 1588493 T8536 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4] 2> 1588494 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 1588496 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 1588498 T8559 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> ASYNC NEW_CORE C2656 name=collection1 org.apache.solr.core.SolrCore@6dd60ddb
[junit4] 2> 1588499 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1497925749203533824)} 0 3
[junit4] 2> 1588499 T8559 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3b62f716[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> ASYNC NEW_CORE C2657 name=collection1 org.apache.solr.core.SolrCore@7a212094
[junit4] 2> 1588503 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1497925749208776704)} 0 1
[junit4] 2> 1588509 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1497925749212971008)]} 0 3
[junit4] 2> 1588514 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1497925749219262464)]} 0 2
[junit4] 2> 1588520 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1497925749227651072)]} 0 1
[junit4] 2> 1588523 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1497925749229748224)]} 0 1
[junit4] 2> 1588526 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1497925749232893952)]} 0 1
[junit4] 2> 1588530 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1497925749237088256)]} 0 1
[junit4] 2> 1588532 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1497925749240233984)]} 0 0
[junit4] 2> 1588536 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1497925749243379712)]} 0 1
[junit4] 2> 1588539 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1497925749247574016)]} 0 1
[junit4] 2> 1588542 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1497925749249671168)]} 0 1
[junit4] 2> 1588545 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1497925749253865472)]} 0 0
[junit4] 2> 1588548 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1497925749257011200)]} 0 1
[junit4] 2> 1588550 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1497925749259108352)]} 0 0
[junit4] 2> 1588553 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1497925749262254080)]} 0 0
[junit4] 2> 1588556 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1497925749264351232)]} 0 1
[junit4] 2> 1588559 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1497925749267496960)]} 0 1
[junit4] 2> 1588561 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1497925749270642688)]} 0 0
[junit4] 2> 1588564 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1497925749272739840)]} 0 1
[junit4] 2> 1588566 T8541 c:collection1 C2656 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1588570 T8541 c:collection1 C2656 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7a1b3219 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35516e54),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7a1b3219 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35516e54),segFN=segments_2,generation=2}
[junit4] 2> 1588571 T8541 c:collection1 C2656 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1588573 T8541 c:collection1 C2656 oass.SolrIndexSearcher.<init> Opening Searcher@145219f3[collection1] main
[junit4] 2> 1588574 T8541 c:collection1 C2656 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1588574 T8547 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@145219f3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C9)))}
[junit4] 2> 1588575 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 9
[junit4] 2> 1588577 T8556 c:collection1 C2657 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1588580 T8556 c:collection1 C2657 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d63a6ab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ccf1d6b),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d63a6ab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ccf1d6b),segFN=segments_2,generation=2}
[junit4] 2> 1588580 T8556 c:collection1 C2657 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1588582 T8556 c:collection1 C2657 oass.SolrIndexSearcher.<init> Opening Searcher@81925b5[collection1] main
[junit4] 2> 1588582 T8556 c:collection1 C2657 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1588586 T8559 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@81925b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C9)))}
[junit4] 2> 1588586 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 9
[junit4] 2> 1588593 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1497925749301051392)]} 0 4
[junit4] 2> 1588601 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1497925749306294272)]} 0 6
[junit4] 2> 1588603 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1497925749314682880)]} 0 0
[junit4] 2> 1588607 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1497925749317828608)]} 0 1
[junit4] 2> 1588609 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1497925749320974336)]} 0 0
[junit4] 2> 1588612 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1497925749324120064)]} 0 0
[junit4] 2> 1588617 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1497925749328314368)]} 0 1
[junit4] 2> 1588622 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1497925749333557248)]} 0 1
[junit4] 2> 1588624 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1497925749336702976)]} 0 0
[junit4] 2> 1588628 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1497925749339848704)]} 0 1
[junit4] 2> 1588631 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1497925749342994432)]} 0 0
[junit4] 2> 1588633 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1497925749346140160)]} 0 0
[junit4] 2> 1588637 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1497925749350334464)]} 0 0
[junit4] 2> 1588640 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1497925749353480192)]} 0 0
[junit4] 2> 1588642 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1497925749355577344)]} 0 0
[junit4] 2> 1588645 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1497925749358723072)]} 0 0
[junit4] 2> 1588647 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1497925749360820224)]} 0 0
[junit4] 2> 1588650 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1497925749363965952)]} 0 0
[junit4] 2> 1588653 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1497925749367111680)]} 0 0
[junit4] 2> 1588655 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1497925749369208832)]} 0 0
[junit4] 2> 1588658 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1497925749372354560)]} 0 0
[junit4] 2> 1588662 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1497925749375500288)]} 0 1
[junit4] 2> 1588664 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1497925749378646016)]} 0 0
[junit4] 2> 1588667 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1497925749381791744)]} 0 0
[junit4] 2> 1588674 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1497925749388083200)]} 0 1
[junit4] 2> 1588679 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1497925749393326080)]} 0 1
[junit4] 2> 1588681 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1497925749396471808)]} 0 0
[junit4] 2> 1588684 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1497925749399617536)]} 0 0
[junit4] 2> 1588688 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1497925749403811840)]} 0 0
[junit4] 2> 1588692 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1497925749408006144)]} 0 0
[junit4] 2> 1588695 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1497925749410103296)]} 0 1
[junit4] 2> 1588697 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1497925749413249024)]} 0 0
[junit4] 2> 1588701 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1497925749417443328)]} 0 0
[junit4] 2> 1588703 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1497925749419540480)]} 0 0
[junit4] 2> 1588706 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1497925749422686208)]} 0 1
[junit4] 2> 1588709 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1497925749425831936)]} 0 0
[junit4] 2> 1588712 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1497925749428977664)]} 0 0
[junit4] 2> 1588714 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1497925749431074816)]} 0 0
[junit4] 2> 1588717 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1497925749433171968)]} 0 1
[junit4] 2> 1588719 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1497925749436317696)]} 0 0
[junit4] 2> 1588722 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1497925749438414848)]} 0 1
[junit4] 2> 1588724 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1497925749441560576)]} 0 0
[junit4] 2> 1588727 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1497925749443657728)]} 0 1
[junit4] 2> 1588729 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1497925749446803456)]} 0 0
[junit4] 2> 1588731 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1497925749448900608)]} 0 0
[junit4] 2> 1588734 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1497925749452046336)]} 0 0
[junit4] 2> 1588736 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1497925749454143488)]} 0 0
[junit4] 2> 1588739 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1497925749457289216)]} 0 0
[junit4] 2> 1588742 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1497925749459386368)]} 0 1
[junit4] 2> 1588744 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1497925749462532096)]} 0 0
[junit4] 2> 1588747 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1497925749465677824)]} 0 0
[junit4] 2> 1588750 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1497925749468823552)]} 0 0
[junit4] 2> 1588753 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1497925749470920704)]} 0 1
[junit4] 2> 1588756 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1497925749474066432)]} 0 1
[junit4] 2> 1588758 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1497925749477212160)]} 0 0
[junit4] 2> 1588761 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1497925749480357888)]} 0 0
[junit4] 2> 1588764 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1497925749483503616)]} 0 0
[junit4] 2> 1588767 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1497925749486649344)]} 0 0
[junit4] 2> 1588770 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1497925749489795072)]} 0 0
[junit4] 2> 1588773 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1497925749492940800)]} 0 0
[junit4] 2> 1588775 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1497925749495037952)]} 0 0
[junit4] 2> 1588778 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1497925749498183680)]} 0 1
[junit4] 2> 1588780 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1497925749500280832)]} 0 0
[junit4] 2> 1588783 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1497925749503426560)]} 0 1
[junit4] 2> 1588785 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1497925749505523712)]} 0 0
[junit4] 2> 1588787 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1497925749507620864)]} 0 0
[junit4] 2> 1588790 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1497925749510766592)]} 0 0
[junit4] 2> 1588792 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1497925749512863744)]} 0 0
[junit4] 2> 1588795 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1497925749516009472)]} 0 0
[junit4] 2> 1588797 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1497925749518106624)]} 0 0
[junit4] 2> 1588800 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1497925749521252352)]} 0 0
[junit4] 2> 1588802 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1497925749523349504)]} 0 0
[junit4] 2> 1588804 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1497925749525446656)]} 0 0
[junit4] 2> 1588807 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1497925749528592384)]} 0 0
[junit4] 2> 1588809 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1497925749530689536)]} 0 0
[junit4] 2> 1588812 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1497925749533835264)]} 0 0
[junit4] 2> 1588814 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1497925749535932416)]} 0 0
[junit4] 2> 1588817 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1497925749539078144)]} 0 0
[junit4] 2> 1588819 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1497925749541175296)]} 0 0
[junit4] 2> 1588821 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1497925749543272448)]} 0 0
[junit4] 2> 1588824 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1497925749546418176)]} 0 0
[junit4] 2> 1588828 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1497925749549563904)]} 0 1
[junit4] 2> 1588833 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1497925749554806784)]} 0 1
[junit4] 2> 1588836 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1497925749559001088)]} 0 0
[junit4] 2> 1588843 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1497925749566341120)]} 0 0
[junit4] 2> 1588845 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1497925749568438272)]} 0 0
[junit4] 2> 1588848 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1497925749571584000)]} 0 0
[junit4] 2> 1588850 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1497925749573681152)]} 0 0
[junit4] 2> 1588853 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1497925749575778304)]} 0 1
[junit4] 2> 1588855 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1497925749578924032)]} 0 0
[junit4] 2> 1588857 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1497925749581021184)]} 0 0
[junit4] 2> 1588859 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1497925749583118336)]} 0 0
[junit4] 2> 1588863 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1497925749587312640)]} 0 1
[junit4] 2> 1588866 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1497925749590458368)]} 0 0
[junit4] 2> 1588868 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1497925749592555520)]} 0 0
[junit4] 2> 1588871 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1497925749595701248)]} 0 0
[junit4] 2> 1588875 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1497925749598846976)]} 0 1
[junit4] 2> 1588878 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1497925749601992704)]} 0 1
[junit4] 2> 1588881 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1497925749606187008)]} 0 0
[junit4] 2> 1588884 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1497925749609332736)]} 0 1
[junit4] 2> 1588887 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1497925749611429888)]} 0 1
[junit4] 2> 1588889 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1497925749614575616)]} 0 0
[junit4] 2> 1588892 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1497925749616672768)]} 0 1
[junit4] 2> 1588894 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1497925749619818496)]} 0 0
[junit4] 2> 1588897 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1497925749622964224)]} 0 0
[junit4] 2> 1588900 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1497925749626109952)]} 0 1
[junit4] 2> 1588903 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1497925749629255680)]} 0 0
[junit4] 2> 1588906 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1497925749632401408)]} 0 0
[junit4] 2> 1588909 T8544 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1497925749634498560)]} 0 1
[junit4] 2> 1588911 T8557 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1497925749637644288)]} 0 0
[junit4] 2> 1588914 T8540 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1497925749640790016)]} 0 0
[junit4] 2> 1588916 T8552 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1497925749642887168)]} 0 0
[junit4] 2> 1588919 T8543 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1497925749646032896)]} 0 0
[junit4] 2> 1588922 T8555 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1497925749649178624)]} 0 0
[junit4] 2> 1588925 T8541 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1497925749652324352)]} 0 0
[junit4] 2> 1588927 T8553 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1497925749654421504)]} 0 0
[junit4] 2> 1588930 T8542 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1497925749657567232)]} 0 0
[junit4] 2> 1588933 T8556 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1497925749660712960)]} 0 0
[junit4] 2> 1588935 T8545 c:collection1 C2656 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1588945 T8545 c:collection1 C2656 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7a1b3219 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35516e54),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7a1b3219 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@35516e54),segFN=segments_3,generation=3}
[junit4] 2> 1588945 T8545 c:collection1 C2656 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1588946 T8545 c:collection1 C2656 oass.SolrIndexSearcher.<init> Opening Searcher@2ff7eedc[collection1] main
[junit4] 2> 1588950 T8545 c:collection1 C2656 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1588950 T8547 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2ff7eedc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C9) Uninverting(_1(5.2.0):C59)))}
[junit4] 2> 1588952 T8545 c:collection1 C2656 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 17
[junit4] 2> 1588955 T8554 c:collection1 C2657 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1588974 T8554 c:collection1 C2657 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d63a6ab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ccf1d6b),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6d63a6ab lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ccf1d6b),segFN=segments_3,generation=3}
[junit4] 2> 1588975 T8554 c:collection1 C2657 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1588976 T8554 c:collection1 C2657 oass.SolrIndexSearcher.<init> Opening Searcher@152cf1f[collection1] main
[junit4] 2> 1588977 T8554 c:collection1 C2657 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1588977 T8559 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@152cf1f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C9) Uninverting(_1(5.2.0):C59)))}
[junit4] 2> 1588978 T8554 c:collection1 C2657 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 23
[junit4] 2> 1589001 T8544 c:collection1 C2656 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: 'start' parameter cannot be negative
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:228)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:197)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1988)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:828)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:102)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1589001 T8544 c:collection1 C2656 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=20
[junit4] 2> 1589004 T8540 c:collection1 C2656 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: 'rows' parameter cannot be negative
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:222)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:197)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1988)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:828)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:102)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1589005 T8540 c:collection1 C2656 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 1589008 T8543 c:collection1 C2656 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1589014 T8552 c:collection1 C2657 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60316//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1428533315413&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1589018 T8555 c:collection1 C2657 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1428533315413&ids=1,134,123,113,126,137,149,127,139,108&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60316//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1589025 T8557 c:collection1 C2657 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60316//collection1|[ff01::083]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=14
[junit4] 2> 1589029 T8541 c:collection1 C2656 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1589042 T8556 c:collection1 C2657 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:60316//collection1&rows=10&version=2&q=*:*&NOW=1428533315434&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1589048 T8554 c:collection1 C2657 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1428533315434&ids=133,13,145,125,103,116,129,119,10,120&isShard=true&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:60316//collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1589058 T8553 c:collection1 C2657 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:60316//collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=26
[junit4] 2> 1589064 T8542 c:collection1 C2656 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1589074 T8555 c:collection1 C2657 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:60316//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1428533315473&isShar
[...truncated too long message...]
olrException: Error from server at http://127.0.0.1:60399//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:356)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:1988)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:828)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:102)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] > at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] > at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] > at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] > at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([3BA82E6E07E7E727:B3FC11B4A91B8ADF]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:556)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:233)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:225)
[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:1055)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1017)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:890)
[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> 1685974 T8536 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3BA82E6E07E7E727-001
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=4266, maxDocsPerChunk=6, blockSize=694), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=4266, blockSize=694)), sim=DefaultSimilarity, locale=ja_JP_JP_#u-ca-japanese, timezone=Europe/Sarajevo
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_40 (64-bit)/cpus=3,threads=1,free=445152992,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [SOLR749Test, TestBlobHandler, TestClusterStateMutator, TestBM25SimilarityFactory, MergeStrategyTest, TestCollectionAPI, PeerSyncTest, ShardRoutingTest, SampleTest, DirectUpdateHandlerTest, DistanceFunctionTest, HttpPartitionTest, CollectionsAPIAsyncDistributedZkTest, DOMUtilTest, DistributedFacetPivotLongTailTest, NumericFieldsTest, UnloadDistributedZkTest, TestBulkSchemaAPI, TestCopyFieldCollectionResource, TestStressRecovery, TestDefaultStatsCache, AlternateDirectoryTest, TestClassNameShortening, TestExpandComponent, UUIDFieldTest, LeaderElectionTest, TestRandomMergePolicy, TestConfigSets, HdfsDirectoryTest, TestComponentsName, TestJmxMonitoredMap, MultiTermTest, TestCSVResponseWriter, TestFaceting, DocValuesMultiTest, CustomCollectionTest, RankQueryTest, SpatialRPTFieldTypeTest, CursorPagingTest, TestImplicitCoreProperties, SimpleMLTQParserTest, TestCloudManagedSchemaConcurrent, QueryElevationComponentTest, DistributedFacetPivotLargeTest, SolrIndexSplitterTest, SolrCoreCheckLockOnStartupTest, ShowFileRequestHandlerTest, SegmentsInfoRequestHandlerTest, TestPostingsSolrHighlighter, UniqFieldsUpdateProcessorFactoryTest, TestPhraseSuggestions, TriLevelCompositeIdRoutingTest, TestStressLucene, TestObjectReleaseTracker, TestStressVersions, JSONWriterTest, TestHashPartitioner, BadCopyFieldTest, TestCoreContainer, DistribCursorPagingTest, SuggestComponentTest, TestExactStatsCache, FieldAnalysisRequestHandlerTest, TestSolrQueryParserDefaultOperatorResource, ConnectionManagerTest, TestJmxIntegration, CursorMarkTest, TestQueryTypes, TestSimpleTrackingShardHandler, TestDocBasedVersionConstraints, RecoveryZkTest, CloudMLTQParserTest, ResponseHeaderTest, TestDocumentBuilder, SuggesterFSTTest, TestCollapseQParserPlugin, PrimUtilsTest, TestRawResponseWriter, TestChildDocTransformer, TestExactSharedStatsCache, SyncSliceTest, SimpleFacetsTest, DistributedQueryElevationComponentTest, LeaderInitiatedRecoveryOnCommitTest, TestElisionMultitermQuery, TestCloudInspectUtil, TestArbitraryIndexDir, HardAutoCommitTest, SimpleCollectionCreateDeleteTest, ShardSplitTest, ParsingFieldUpdateProcessorsTest, TestPerFieldSimilarity, ExitableDirectoryReaderTest, HdfsCollectionsAPIDistributedZkTest, SolrCoreTest, ChaosMonkeyNothingIsSafeTest, TestMiniSolrCloudClusterSSL, TestFunctionQuery, TestOmitPositions, DeleteShardTest, TestJettySolrRunner, TestSearcherReuse, TestRandomDVFaceting, RequiredFieldsTest, TestIBSimilarityFactory, TestIndexingPerformance, TestUniqueKeyFieldResource, HdfsDirectoryFactoryTest, TestLeaderElectionZkExpiry, FacetPivotSmallTest, TestJsonRequest, TestRebalanceLeaders, TestSweetSpotSimilarityFactory, TestInitQParser, BasicZkTest, TestReplicationHandler, TestZkChroot, TestDistributedSearch]
[junit4] Completed on J0 in 99.64s, 1 test, 1 error <<< FAILURES!
[...truncated 700 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:529: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:477: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:191: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:509: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1370: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:976: There were test failures: 480 suites, 1910 tests, 1 error, 53 ignored (20 assumptions)
Total time: 72 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any