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/05/10 11:12:53 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 2286
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2286/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Error from server at http://127.0.0.1:56772/deqds/zk/collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:388) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2047) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:578) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:378) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:175) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:168) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) at org.eclipse.jetty.server.Server.handle(Server.java:497) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:56772/deqds/zk/collection1: java.lang.NullPointerException
at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102)
at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744)
at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727)
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:388)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2047)
at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:578)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:378)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:175)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:168)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
at org.eclipse.jetty.server.Server.handle(Server.java:497)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([3A1D236895E82B0:8BF5EDEC27A2EF48]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9562 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/init-core-data-001
[junit4] 2> 79928 T285 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 79929 T285 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /deqds/zk
[junit4] 2> 80468 T285 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores/collection1
[junit4] 2> 80474 T285 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 80485 T285 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@52cfe63a{/deqds/zk,null,AVAILABLE}
[junit4] 2> 80498 T285 oejs.AbstractConnector.doStart Started ServerConnector@357f1c6c{HTTP/1.1}{127.0.0.1:56674}
[junit4] 2> 80499 T285 oejs.Server.doStart Started @85396ms
[junit4] 2> 80499 T285 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/deqds/zk, hostPort=56674, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores}
[junit4] 2> 80505 T285 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 80507 T285 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/'
[junit4] 2> 80558 T285 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/solr.xml
[junit4] 2> 80573 T285 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores
[junit4] 2> 80586 T285 oasc.CoreContainer.<init> New CoreContainer 1406020136
[junit4] 2> 80586 T285 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/]
[junit4] 2> 80587 T285 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/lib
[junit4] 2> 80587 T285 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/lib).
[junit4] 2> 80616 T285 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 80640 T285 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 80642 T285 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 80642 T285 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 80642 T285 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 80645 T285 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores
[junit4] 2> 80649 T285 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/}
[junit4] 2> 80649 T285 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores/collection1/
[junit4] 2> 80650 T285 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 80652 T297 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 80653 T297 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 80654 T297 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 80729 T297 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 80740 T297 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 80810 T297 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 80811 T297 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 80834 T297 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 81158 T297 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 81179 T297 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 81180 T297 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 81188 T297 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 81193 T297 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 81204 T297 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 81205 T297 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 81205 T297 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 81205 T297 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 81206 T297 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 81206 T297 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 81206 T297 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 81206 T297 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 81207 T297 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 81207 T297 x:collection1 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 81208 T297 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores/collection1/data
[junit4] 2> 81208 T297 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores/collection1/data/index/
[junit4] 2> 81208 T297 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 81208 T297 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/control/cores/collection1/data/index
[junit4] 2> 81209 T297 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 81224 T297 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62382912 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@42233212),segFN=segments_1,generation=1}
[junit4] 2> 81225 T297 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 81229 T297 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 81230 T297 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 81230 T297 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 81230 T297 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 81230 T297 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 81231 T297 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 81231 T297 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 81233 T297 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 81233 T297 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 81240 T297 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 81241 T297 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 81241 T297 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 81259 T297 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 81261 T297 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 81264 T297 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 81269 T297 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 81273 T297 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 81275 T297 x: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> 81293 T297 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 81294 T297 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 81298 T297 x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 81298 T297 x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 81300 T297 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 81300 T297 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 81303 T297 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 81304 T297 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62382912 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@42233212),segFN=segments_1,generation=1}
[junit4] 2> 81304 T297 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 81304 T297 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@5870a2ff[collection1] main
[junit4] 2> 81306 T297 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 81309 T297 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 81310 T297 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 81310 T297 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 81344 T297 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 81416 T297 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 81417 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 81417 T297 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 81419 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 81420 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 81420 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 81421 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 81422 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 81423 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 81424 T285 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 81425 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 81425 T285 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 81426 T298 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 81427 T298 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5870a2ff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 82404 T285 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 82413 T285 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 82420 T285 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@5ea0dd50{/deqds/zk,null,AVAILABLE}
[junit4] 2> 82422 T285 oejs.AbstractConnector.doStart Started ServerConnector@72faefea{HTTP/1.1}{127.0.0.1:56676}
[junit4] 2> 82422 T285 oejs.Server.doStart Started @87317ms
[junit4] 2> 82422 T285 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/deqds/zk, hostPort=56676, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores}
[junit4] 2> 82423 T285 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 82424 T285 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/'
[junit4] 2> 82532 T285 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/solr.xml
[junit4] 2> 82551 T285 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores
[junit4] 2> 82552 T285 oasc.CoreContainer.<init> New CoreContainer 872148996
[junit4] 2> 82552 T285 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/]
[junit4] 2> 82553 T285 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/lib
[junit4] 2> 82554 T285 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/lib).
[junit4] 2> 82579 T285 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 82583 T285 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 82585 T285 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 82586 T285 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 82586 T285 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 82595 T285 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores
[junit4] 2> 82597 T285 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/}
[junit4] 2> 82598 T285 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores/collection1/
[junit4] 2> 82598 T285 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 82600 T311 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 82602 T311 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 82602 T311 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 82656 T311 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 82679 T311 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 82734 T311 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 82736 T311 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 82760 T311 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 82985 T311 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 82998 T311 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 83001 T311 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 83012 T311 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 83016 T311 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 83020 T311 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 83022 T311 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 83022 T311 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 83023 T311 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 83023 T311 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 83024 T311 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 83024 T311 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 83024 T311 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 83025 T311 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 83025 T311 x:collection1 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4] 2> 83026 T311 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores/collection1/data
[junit4] 2> 83027 T311 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores/collection1/data/index/
[junit4] 2> 83027 T311 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 83027 T311 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard0/cores/collection1/data/index
[junit4] 2> 83027 T311 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 83030 T311 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6800707f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d09c868),segFN=segments_1,generation=1}
[junit4] 2> 83030 T311 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 83036 T311 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 83036 T311 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 83037 T311 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 83037 T311 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 83037 T311 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 83038 T311 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 83039 T311 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 83039 T311 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 83039 T311 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 83040 T311 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 83040 T311 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 83040 T311 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 83051 T311 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 83054 T311 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 83056 T311 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 83059 T311 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 83070 T311 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 83072 T311 x: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> 83073 T311 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 83074 T311 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 83077 T311 x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 83077 T311 x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 83090 T311 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 83090 T311 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 83091 T311 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 83091 T311 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6800707f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d09c868),segFN=segments_1,generation=1}
[junit4] 2> 83091 T311 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 83092 T311 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@1f28f08[collection1] main
[junit4] 2> 83094 T311 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 83095 T311 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 83095 T311 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 83095 T311 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 83119 T311 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 83207 T311 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 83208 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 83208 T311 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 83211 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 83211 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 83211 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 83211 T285 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 83212 T285 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 83212 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 83214 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 83215 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 83216 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 83217 T312 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 83217 T312 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f28f08[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 83219 T285 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4] 2> ASYNC NEW_CORE C162 name=collection1 org.apache.solr.core.SolrCore@34aa35f4
[junit4] 2> 83260 T289 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1500770672023961600)} 0 9
[junit4] 2> ASYNC NEW_CORE C163 name=collection1 org.apache.solr.core.SolrCore@6db7aa0d
[junit4] 2> 83276 T303 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1500770672041787392)} 0 5
[junit4] 2> 83308 T290 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[1 (1500770672064856064)]} 0 15
[junit4] 2> 83322 T304 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[1 (1500770672085827584)]} 0 9
[junit4] 2> 83343 T291 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[2 (1500770672113090560)]} 0 4
[junit4] 2> 83358 T305 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[2 (1500770672121479168)]} 0 12
[junit4] 2> 83372 T292 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[3 (1500770672146644992)]} 0 2
[junit4] 2> 83381 T306 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[3 (1500770672155033600)]} 0 3
[junit4] 2> 83388 T293 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[4 (1500770672163422208)]} 0 1
[junit4] 2> 83394 T307 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[4 (1500770672169713664)]} 0 1
[junit4] 2> 83402 T294 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[5 (1500770672178102272)]} 0 1
[junit4] 2> 83408 T308 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[5 (1500770672183345152)]} 0 2
[junit4] 2> 83420 T289 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[6 (1500770672196976640)]} 0 2
[junit4] 2> 83425 T303 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[6 (1500770672202219520)]} 0 1
[junit4] 2> 83431 T290 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[7 (1500770672208510976)]} 0 1
[junit4] 2> 83436 T304 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[7 (1500770672213753856)]} 0 1
[junit4] 2> 83441 T291 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[8 (1500770672218996736)]} 0 1
[junit4] 2> 83446 T305 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[8 (1500770672224239616)]} 0 2
[junit4] 2> 83455 T292 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[9 (1500770672230531072)]} 0 4
[junit4] 2> 83465 T306 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[9 (1500770672244162560)]} 0 1
[junit4] 2> 83468 T293 x:collection1 C162 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 83554 T293 x:collection1 C162 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62382912 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@42233212),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62382912 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@42233212),segFN=segments_2,generation=2}
[junit4] 2> 83554 T293 x:collection1 C162 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 83572 T293 x:collection1 C162 oass.SolrIndexSearcher.<init> Opening Searcher@532f745b[collection1] main
[junit4] 2> 83573 T293 x:collection1 C162 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 83573 T298 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@532f745b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9)))}
[junit4] 2> 83584 T293 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 115
[junit4] 2> 83611 T307 x:collection1 C163 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 83659 T307 x:collection1 C163 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6800707f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d09c868),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6800707f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d09c868),segFN=segments_2,generation=2}
[junit4] 2> 83661 T307 x:collection1 C163 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 83664 T307 x:collection1 C163 oass.SolrIndexSearcher.<init> Opening Searcher@6e10e5a5[collection1] main
[junit4] 2> 83666 T307 x:collection1 C163 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 83666 T312 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e10e5a5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9)))}
[junit4] 2> 83676 T307 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 75
[junit4] 2> 83692 T294 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[10 (1500770672475897856)]} 0 8
[junit4] 2> 83714 T308 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[10 (1500770672494772224)]} 0 15
[junit4] 2> 83730 T289 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[11 (1500770672520986624)]} 0 2
[junit4] 2> 83752 T303 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[11 (1500770672545103872)]} 0 2
[junit4] 2> 83772 T290 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[12 (1500770672566075392)]} 0 1
[junit4] 2> 83786 T304 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[12 (1500770672580755456)]} 0 1
[junit4] 2> 83798 T291 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[13 (1500770672593338368)]} 0 0
[junit4] 2> 83817 T305 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[13 (1500770672613261312)]} 0 2
[junit4] 2> 83835 T292 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[1001 (1500770672631087104)]} 0 2
[junit4] 2> 83858 T306 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[1001 (1500770672655204352)]} 0 12
[junit4] 2> 83898 T293 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[14 (1500770672691904512)]} 0 7
[junit4] 2> 83911 T307 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[14 (1500770672711827456)]} 0 1
[junit4] 2> 83930 T294 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[15 (1500770672731750400)]} 0 1
[junit4] 2> 83943 T308 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[15 (1500770672745381888)]} 0 1
[junit4] 2> 83955 T289 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[16 (1500770672757964800)]} 0 1
[junit4] 2> 83970 T303 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[16 (1500770672773693440)]} 0 1
[junit4] 2> 83989 T290 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[17 (1500770672792567808)]} 0 3
[junit4] 2> 84034 T304 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[17 (1500770672839753728)]} 0 3
[junit4] 2> 84056 T291 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[100 (1500770672862822400)]} 0 2
[junit4] 2> 84076 T305 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[100 (1500770672885891072)]} 0 0
[junit4] 2> 84098 T292 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[101 (1500770672908959744)]} 0 1
[junit4] 2> 84124 T306 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[101 (1500770672936222720)]} 0 1
[junit4] 2> 84158 T293 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[102 (1500770672970825728)]} 0 13
[junit4] 2> 84162 T307 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[102 (1500770672976068608)]} 0 0
[junit4] 2> 84168 T294 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[103 (1500770672981311488)]} 0 1
[junit4] 2> 84173 T308 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[103 (1500770672987602944)]} 0 1
[junit4] 2> 84184 T289 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[104 (1500770672998088704)]} 0 1
[junit4] 2> 84219 T303 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[104 (1500770673034788864)]} 0 1
[junit4] 2> 84235 T290 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[105 (1500770673050517504)]} 0 2
[junit4] 2> 84249 T304 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[105 (1500770673066246144)]} 0 1
[junit4] 2> 84262 T291 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[106 (1500770673079877632)]} 0 1
[junit4] 2> 84278 T305 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[106 (1500770673097703424)]} 0 0
[junit4] 2> 84291 T292 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[107 (1500770673110286336)]} 0 1
[junit4] 2> 84305 T306 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[107 (1500770673126014976)]} 0 1
[junit4] 2> 84310 T293 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[108 (1500770673131257856)]} 0 0
[junit4] 2> 84332 T307 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[108 (1500770673153277952)]} 0 1
[junit4] 2> 84339 T294 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[109 (1500770673160617984)]} 0 1
[junit4] 2> 84345 T308 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[109 (1500770673166909440)]} 0 1
[junit4] 2> 84350 T289 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[110 (1500770673173200896)]} 0 1
[junit4] 2> 84357 T303 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[110 (1500770673179492352)]} 0 1
[junit4] 2> 84363 T290 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[111 (1500770673185783808)]} 0 1
[junit4] 2> 84384 T304 x:collection1 C163 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[111 (1500770673206755328)]} 0 2
[junit4] 2> 84389 T291 x:collection1 C162 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[112 (1500770673213046784)]} 0 1
[junit4] 2> ASYNC NEW_CORE C164 name=collection1 org.apache.solr.core.SolrCore@6db7aa0d
[junit4] 2> 84414 T305 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[112 (1500770673219338240)]} 0 21
[junit4] 2> ASYNC NEW_CORE C165 name=collection1 org.apache.solr.core.SolrCore@34aa35f4
[junit4] 2> 84420 T292 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[113 (1500770673245552640)]} 0 1
[junit4] 2> 84424 T306 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[113 (1500770673250795520)]} 0 0
[junit4] 2> 84430 T293 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[114 (1500770673256038400)]} 0 2
[junit4] 2> 84435 T307 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[114 (1500770673262329856)]} 0 1
[junit4] 2> 84440 T294 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[115 (1500770673267572736)]} 0 1
[junit4] 2> 84445 T308 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[115 (1500770673271767040)]} 0 1
[junit4] 2> 84450 T289 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[116 (1500770673277009920)]} 0 1
[junit4] 2> 84454 T303 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[116 (1500770673282252800)]} 0 0
[junit4] 2> 84461 T290 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[117 (1500770673288544256)]} 0 2
[junit4] 2> 84465 T304 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[117 (1500770673293787136)]} 0 1
[junit4] 2> 84476 T291 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[118 (1500770673304272896)]} 0 2
[junit4] 2> 84486 T305 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[118 (1500770673314758656)]} 0 1
[junit4] 2> 84490 T292 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[119 (1500770673320001536)]} 0 0
[junit4] 2> 84503 T306 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[119 (1500770673332584448)]} 0 1
[junit4] 2> 84516 T293 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[120 (1500770673346215936)]} 0 1
[junit4] 2> 84520 T307 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[120 (1500770673351458816)]} 0 0
[junit4] 2> 84532 T294 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[121 (1500770673362993152)]} 0 2
[junit4] 2> 84539 T308 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[121 (1500770673370333184)]} 0 1
[junit4] 2> 84553 T289 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[122 (1500770673385013248)]} 0 1
[junit4] 2> 84565 T303 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[122 (1500770673397596160)]} 0 2
[junit4] 2> 84573 T290 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[123 (1500770673404936192)]} 0 2
[junit4] 2> 84586 T304 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[123 (1500770673420664832)]} 0 1
[junit4] 2> 84594 T291 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[124 (1500770673428004864)]} 0 1
[junit4] 2> 84607 T305 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[124 (1500770673441636352)]} 0 1
[junit4] 2> 84626 T292 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[125 (1500770673461559296)]} 0 1
[junit4] 2> 84643 T306 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[125 (1500770673480433664)]} 0 1
[junit4] 2> 84656 T293 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[126 (1500770673493016576)]} 0 1
[junit4] 2> 84665 T307 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[126 (1500770673502453760)]} 0 1
[junit4] 2> 84679 T294 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[127 (1500770673517133824)]} 0 1
[junit4] 2> 84695 T308 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[127 (1500770673532862464)]} 0 2
[junit4] 2> 84709 T289 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[128 (1500770673549639680)]} 0 1
[junit4] 2> 84722 T303 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[128 (1500770673562222592)]} 0 1
[junit4] 2> 84733 T290 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[129 (1500770673574805504)]} 0 1
[junit4] 2> 84749 T304 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[129 (1500770673591582720)]} 0 1
[junit4] 2> 84762 T291 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[130 (1500770673604165632)]} 0 1
[junit4] 2> 84783 T305 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[130 (1500770673627234304)]} 0 1
[junit4] 2> 84795 T292 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[131 (1500770673638768640)]} 0 1
[junit4] 2> 84825 T306 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[131 (1500770673671274496)]} 0 0
[junit4] 2> 84838 T293 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[132 (1500770673684905984)]} 0 1
[junit4] 2> 84843 T307 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[132 (1500770673689100288)]} 0 1
[junit4] 2> 84855 T294 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[133 (1500770673701683200)]} 0 1
[junit4] 2> 84859 T308 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[133 (1500770673706926080)]} 0 1
[junit4] 2> 84865 T289 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[134 (1500770673712168960)]} 0 1
[junit4] 2> 84870 T303 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[134 (1500770673718460416)]} 0 0
[junit4] 2> 84886 T290 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[135 (1500770673734189056)]} 0 1
[junit4] 2> 84904 T304 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[135 (1500770673750966272)]} 0 4
[junit4] 2> 84920 T291 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[136 (1500770673769840640)]} 0 1
[junit4] 2> 84927 T305 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[136 (1500770673777180672)]} 0 1
[junit4] 2> 84933 T292 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[137 (1500770673782423552)]} 0 2
[junit4] 2> 84941 T306 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[137 (1500770673792909312)]} 0 1
[junit4] 2> 84956 T293 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[138 (1500770673807589376)]} 0 1
[junit4] 2> 84968 T307 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[138 (1500770673819123712)]} 0 2
[junit4] 2> 84980 T294 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[139 (1500770673832755200)]} 0 1
[junit4] 2> 84986 T308 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[139 (1500770673839046656)]} 0 1
[junit4] 2> 84995 T289 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[140 (1500770673848483840)]} 0 1
[junit4] 2> 85015 T303 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[140 (1500770673870503936)]} 0 1
[junit4] 2> 85030 T290 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[141 (1500770673885184000)]} 0 1
[junit4] 2> 85043 T304 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[141 (1500770673898815488)]} 0 1
[junit4] 2> 85063 T291 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[142 (1500770673919787008)]} 0 2
[junit4] 2> 85080 T305 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[142 (1500770673937612800)]} 0 2
[junit4] 2> 85094 T292 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[143 (1500770673952292864)]} 0 2
[junit4] 2> 85109 T306 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[143 (1500770673968021504)]} 0 1
[junit4] 2> 85120 T293 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[144 (1500770673979555840)]} 0 1
[junit4] 2> 85154 T307 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[144 (1500770674015207424)]} 0 2
[junit4] 2> 85171 T294 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[145 (1500770674031984640)]} 0 2
[junit4] 2> 85205 T308 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[145 (1500770674068684800)]} 0 1
[junit4] 2> 85216 T289 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[146 (1500770674081267712)]} 0 1
[junit4] 2> 85230 T303 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[146 (1500770674095947776)]} 0 1
[junit4] 2> 85268 T290 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[147 (1500770674134745088)]} 0 1
[junit4] 2> 85278 T304 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[147 (1500770674145230848)]} 0 1
[junit4] 2> 85289 T291 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[148 (1500770674156765184)]} 0 1
[junit4] 2> 85304 T305 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[148 (1500770674171445248)]} 0 2
[junit4] 2> 85330 T292 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[149 (1500770674200805376)]} 0 1
[junit4] 2> 85344 T306 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={wt=javabin&version=2} {add=[149 (1500770674214436864)]} 0 1
[junit4] 2> 85349 T293 x:collection1 C165 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 85437 T293 x:collection1 C165 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62382912 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@42233212),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@62382912 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@42233212),segFN=segments_3,generation=3}
[junit4] 2> 85437 T293 x:collection1 C165 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 85439 T293 x:collection1 C165 oass.SolrIndexSearcher.<init> Opening Searcher@58ebd86f[collection1] main
[junit4] 2> 85440 T293 x:collection1 C165 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 85440 T298 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@58ebd86f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9) Uninverting(_1(6.0.0):c59)))}
[junit4] 2> 85444 T293 x:collection1 C165 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 96
[junit4] 2> 85450 T307 x:collection1 C164 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 85579 T307 x:collection1 C164 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6800707f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d09c868),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6800707f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d09c868),segFN=segments_3,generation=3}
[junit4] 2> 85580 T307 x:collection1 C164 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 85581 T307 x:collection1 C164 oass.SolrIndexSearcher.<init> Opening Searcher@468c0034[collection1] main
[junit4] 2> 85582 T307 x:collection1 C164 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 85582 T312 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@468c0034[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9) Uninverting(_1(6.0.0):c59)))}
[junit4] 2> 85583 T307 x:collection1 C164 oasup.LogUpdateProcessor.finish [collection1] webapp=/deqds/zk path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 133
[junit4] 2> 85589 T294 x:collection1 C165 oasc.SolrException.log Ignoring exception matching parameter cannot be negative
[junit4] 2> 85589 T294 x:collection1 C165 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=2
[junit4] 2> 85595 T289 x:collection1 C165 oasc.SolrException.log Ignoring exception matching parameter cannot be negative
[junit4] 2> 85595 T289 x:collection1 C165 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 85609 T290 x:collection1 C165 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 85662 T303 x:collection1 C164 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:56676/deqds/zk/collection1&rows=10&version=2&q=*:*&NOW=1431246447111&isShard=true&wt=javabin} hits=68 status=0 QTime=14
[junit4] 2> 85696 T304 x:collection1 C164 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447111&ids=132,100,122,112,15,125,148,17,116,8&isShard=true&shard.url=http://127.0.0.1:56676/deqds/zk/collection1&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 85714 T308 x:collection1 C164 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=http://127.0.0.1:56676/deqds/zk/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=74
[junit4] 2> ASYNC NEW_CORE C166 name=collection1 org.apache.solr.core.SolrCore@34aa35f4
[junit4] 2> 85779 T291 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> ASYNC NEW_CORE C167 name=collection1 org.apache.solr.core.SolrCore@6db7aa0d
[junit4] 2> 85834 T306 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:56676/deqds/zk/collection1&rows=10&version=2&q=*:*&NOW=1431246447270&isShard=true&wt=javabin} hits=68 status=0 QTime=30
[junit4] 2> 85851 T307 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447270&ids=2,123,102,4,138,105,6,129,130,120&isShard=true&shard.url=http://127.0.0.1:56676/deqds/zk/collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 85880 T305 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=http://127.0.0.1:56676/deqds/zk/collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=82
[junit4] 2> 85901 T292 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 85917 T304 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk 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:56676/deqds/zk/collection1&rows=10&version=2&q=*:*&NOW=1431246447378&isShard=true&wt=javabin} hits=68 status=0 QTime=7
[junit4] 2> 85925 T308 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447378&ids=13,14,103,4,125,104,117,128,130,120&isShard=true&shard.url=http://127.0.0.1:56676/deqds/zk/collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 85965 T303 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=http://127.0.0.1:56676/deqds/zk/collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=58
[junit4] 2> 85975 T293 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 86007 T307 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::213]:33332/deqds/zk|[ff01::114]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1&rows=10&version=2&q=*:*&NOW=1431246447466&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 86013 T305 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447466&ids=143,121,111,144,123,145,6,138,105,139&isShard=true&shard.url=[ff01::213]:33332/deqds/zk|[ff01::114]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 86042 T306 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=[ff01::213]:33332/deqds/zk|[ff01::114]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=47
[junit4] 2> 86054 T294 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 86073 T303 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:56676/deqds/zk/collection1|[ff01::083]:33332/deqds/zk|[ff01::114]:33332/deqds/zk&rows=10&version=2&q=*:*&NOW=1431246447532&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 86090 T308 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447532&ids=2,113,14,114,125,137,5,139,107,119&isShard=true&shard.url=http://127.0.0.1:56676/deqds/zk/collection1|[ff01::083]:33332/deqds/zk|[ff01::114]:33332/deqds/zk&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 86115 T304 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=http://127.0.0.1:56676/deqds/zk/collection1|[ff01::083]:33332/deqds/zk|[ff01::114]:33332/deqds/zk&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=54
[junit4] 2> 86131 T289 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 86217 T305 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/deqds/zk|[ff01::114]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1|[ff01::213]:33332/deqds/zk&rows=10&version=2&q=*:*&NOW=1431246447633&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 86230 T306 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447633&ids=110,121,134,146,15,103,116,105,129,131&isShard=true&shard.url=[ff01::083]:33332/deqds/zk|[ff01::114]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1|[ff01::213]:33332/deqds/zk&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 86247 T307 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=[ff01::083]:33332/deqds/zk|[ff01::114]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1|[ff01::213]:33332/deqds/zk&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=85
[junit4] 2> 86256 T290 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 86270 T308 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:56676/deqds/zk/collection1|[ff01::213]:33332/deqds/zk&rows=10&version=2&q=*:*&NOW=1431246447732&isShard=true&wt=javabin} hits=68 status=0 QTime=2
[junit4] 2> 86282 T304 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447732&ids=134,102,136,15,115,148,138,7,130,10&isShard=true&shard.url=http://127.0.0.1:56676/deqds/zk/collection1|[ff01::213]:33332/deqds/zk&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 86285 T303 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=http://127.0.0.1:56676/deqds/zk/collection1|[ff01::213]:33332/deqds/zk&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=24
[junit4] 2> 86291 T291 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 86314 T306 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:56676/deqds/zk/collection1&rows=10&version=2&q=*:*&NOW=1431246447771&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 86322 T307 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447771&ids=2,135,14,126,127,105,1001,117,9,142&isShard=true&shard.url=http://127.0.0.1:56676/deqds/zk/collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 86324 T305 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=http://127.0.0.1:56676/deqds/zk/collection1&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=24
[junit4] 2> 86337 T292 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 86365 T304 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1|[ff01::114]:33332/deqds/zk&rows=10&version=2&q=*:*&NOW=1431246447821&isShard=true&wt=javabin} hits=68 status=0 QTime=2
[junit4] 2> 86436 T303 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431246447821&ids=110,133,112,14,146,16,127,117,8,130&isShard=true&shard.url=[ff01::213]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1|[ff01::114]:33332/deqds/zk&wt=javabin&version=2} status=0 QTime=21
[junit4] 2> 86459 T308 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&shards=[ff01::213]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1|[ff01::114]:33332/deqds/zk&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=109
[junit4] 2> 86466 T293 x:collection1 C166 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 86483 T307 x:collection1 C167 oasc.SolrCore.execute [collection1] webapp=/deqds/zk path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/deqds/zk|http://127.0.0.1:56676/deqds/zk/collection1&rows=10&version=2&q=*:*&NOW=1431246447943&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[
[...truncated too long message...]
y.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard2/cores/collection1/data
[junit4] 2> 343140 T285 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard2/cores/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard2/cores/collection1/data/index;done=false>>]
[junit4] 2> 343140 T285 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001/tempDir-001/shard2/cores/collection1/data/index
[junit4] 2> 343145 T285 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@5d3bad8b{/deqds/zk,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=3A1D236895E82B0 -Dtests.slow=true -Dtests.locale=es_EC -Dtests.timezone=Europe/Luxembourg -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 263s J0 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:56772/deqds/zk/collection1: java.lang.NullPointerException
[junit4] > at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102)
[junit4] > at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744)
[junit4] > at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727)
[junit4] > at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:388)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2047)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:578)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:378)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:175)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:168)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
[junit4] > at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:497)
[junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
[junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
[junit4] > at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([3A1D236895E82B0:8BF5EDEC27A2EF48]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
[junit4] > at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 343179 T285 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 3A1D236895E82B0-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=PostingsFormat(name=MockRandom), range_facet_l_dv=PostingsFormat(name=LuceneVarGapFixedInterval), SubjectTerms_mfacet=PostingsFormat(name=MockRandom), n_l1=PostingsFormat(name=MockRandom), a_n_tdt=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=Lucene50(blocksize=128), intDefault=PostingsFormat(name=MockRandom), n_td1=Lucene50(blocksize=128), n_d1=PostingsFormat(name=MockRandom), n_f1=Lucene50(blocksize=128), range_facet_l=PostingsFormat(name=MockRandom), n_tl1=Lucene50(blocksize=128), oddField_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_tf1=PostingsFormat(name=MockRandom), lowerfilt1and2=Lucene50(blocksize=128), a_t=PostingsFormat(name=MockRandom), lowerfilt=Lucene50(blocksize=128), id=PostingsFormat(name=LuceneVarGapFixedInterval), text=PostingsFormat(name=LuceneVarGapDocFreqInterval), timestamp=PostingsFormat(name=MockRandom), severity=PostingsFormat(name=LuceneVarGapDocFreqInterval), foo_sev_enum=PostingsFormat(name=LuceneVarGapFixedInterval), n_dt1=Lucene50(blocksize=128), a_i1=PostingsFormat(name=MockRandom), b_n_tdt=PostingsFormat(name=MockRandom), n_ti1=PostingsFormat(name=LuceneVarGapFixedInterval), rnd_b=PostingsFormat(name=LuceneVarGapFixedInterval), _version_=PostingsFormat(name=MockRandom), foo_b=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_tdt1=PostingsFormat(name=MockRandom), foo_d=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_i_dv=PostingsFormat(name=MockRandom), foo_f=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), foo_sev_enum=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=es_EC, timezone=Europe/Luxembourg
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=3,threads=2,free=43818336,total=120131584
[junit4] 2> NOTE: All tests run in this JVM: [TestClassNameShortening, TestLFUCache, HdfsUnloadDistributedZkTest, DistributedFacetPivotLargeTest, TestLazyCores, PrimUtilsTest, TestWriterPerf, TestManagedSchemaFieldTypeResource, DateRangeFieldTest, TestExpandComponent, TestDistributedSearch]
[junit4] Completed [71/488] on J0 in 263.31s, 1 test, 1 error <<< FAILURES!
[...truncated 1296 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:526: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:474: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:229: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:512: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1415: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:973: There were test failures: 488 suites, 1956 tests, 1 error, 62 ignored (24 assumptions)
Total time: 69 minutes 3 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any