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
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
