Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2317/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed. FAILED: org.apache.solr.TestDistributedSearch.test Error Message: Error from server at http://127.0.0.1:62418//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:636) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:436) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:198) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:178) 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:62418//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:636) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:436) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:198) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:178) 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([71DB1AE79D18C61D:F98F253D33E4ABE5]: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 10065 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 71DB1AE79D18C61D-001/init-core-data-001 [junit4] 2> 1499814 T7210 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 1499814 T7210 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 1500227 T7210 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/cores/collection1 [junit4] 2> 1500231 T7210 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 1500236 T7210 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@38bec35d{/,null,AVAILABLE} [junit4] 2> 1500237 T7210 oejs.AbstractConnector.doStart Started ServerConnector@377ff3f2{HTTP/1.1}{127.0.0.1:62359} [junit4] 2> 1500237 T7210 oejs.Server.doStart Started @1502456ms [junit4] 2> 1500237 T7210 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=62359, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/cores} [junit4] 2> 1500238 T7210 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482 [junit4] 2> 1500238 T7210 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/' [junit4] 2> 1500272 T7210 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/solr.xml [junit4] 2> 1500284 T7210 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/cores [junit4] 2> 1500284 T7210 oasc.CoreContainer.<init> New CoreContainer 1726192726 [junit4] 2> 1500285 T7210 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/] [junit4] 2> 1500285 T7210 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/lib [junit4] 2> 1500286 T7210 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 71DB1AE79D18C61D-001/tempDir-001/control/lib). [junit4] 2> 1500294 T7210 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> 1500299 T7210 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1500299 T7210 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1500300 T7210 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1500300 T7210 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1500303 T7210 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/cores [junit4] 2> 1500305 T7210 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 71DB1AE79D18C61D-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 1500305 T7210 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/control/cores/collection1/ [junit4] 2> 1500305 T7210 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1500306 T7222 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1500307 T7222 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> 1500307 T7222 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> 1500348 T7222 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1500361 T7222 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1500390 T7222 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1500391 T7222 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> 1500396 T7222 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1500533 T7222 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1500541 T7222 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1500542 T7222 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1500549 T7222 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1500550 T7222 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1500552 T7222 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1500553 T7222 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1500554 T7222 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1500554 T7222 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1500555 T7222 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1500555 T7222 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1500555 T7222 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> 1500556 T7222 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1500556 T7222 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> 1500556 T7222 x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ed73e07 [junit4] 2> 1500557 T7222 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 71DB1AE79D18C61D-001/tempDir-001/control/cores/collection1/data [junit4] 2> 1500557 T7222 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 71DB1AE79D18C61D-001/tempDir-001/control/cores/collection1/data/index/ [junit4] 2> 1500558 T7222 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 71DB1AE79D18C61D-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1500558 T7222 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 71DB1AE79D18C61D-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 1500558 T7222 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=37.5087890625, floorSegmentMB=1.5625, forceMergeDeletesPctAllowed=28.389685894081047, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6283865442706992 [junit4] 2> 1500559 T7222 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ea1cd59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c512a1a),segFN=segments_1,generation=1} [junit4] 2> 1500559 T7222 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1500565 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1500566 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1500566 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1500566 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1500566 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1500566 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1500567 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1500567 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1500567 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1500568 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1500568 T7222 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1500568 T7222 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1500575 T7222 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1500576 T7222 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1500577 T7222 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1500578 T7222 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1500585 T7222 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1500588 T7222 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> 1500589 T7222 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1500589 T7222 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1500591 T7222 x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1500592 T7222 x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1500593 T7222 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1500593 T7222 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1500594 T7222 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=89.6259765625, floorSegmentMB=1.314453125, forceMergeDeletesPctAllowed=14.506972958832876, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1500594 T7222 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ea1cd59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c512a1a),segFN=segments_1,generation=1} [junit4] 2> 1500594 T7222 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1500595 T7222 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@50bf86d6[collection1] main [junit4] 2> 1500595 T7222 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1500596 T7222 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1500596 T7222 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1500596 T7222 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1500604 T7222 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1500638 T7222 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1500639 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1500640 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1500640 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1500640 T7222 x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1500640 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1500642 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1500642 T7210 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1500642 T7210 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1500642 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1500644 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1500645 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1500646 T7223 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1500647 T7223 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50bf86d6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1501150 T7210 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 1501155 T7210 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 1501164 T7210 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@a6cb600{/,null,AVAILABLE} [junit4] 2> 1501165 T7210 oejs.AbstractConnector.doStart Started ServerConnector@1153505{HTTP/1.1}{127.0.0.1:62360} [junit4] 2> 1501166 T7210 oejs.Server.doStart Started @1503384ms [junit4] 2> 1501166 T7210 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=62360, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/cores} [junit4] 2> 1501167 T7210 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482 [junit4] 2> 1501167 T7210 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/' [junit4] 2> 1501269 T7210 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/solr.xml [junit4] 2> 1501283 T7210 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/cores [junit4] 2> 1501288 T7210 oasc.CoreContainer.<init> New CoreContainer 1633234814 [junit4] 2> 1501289 T7210 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/] [junit4] 2> 1501289 T7210 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/lib [junit4] 2> 1501291 T7210 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 71DB1AE79D18C61D-001/tempDir-001/shard0/lib). [junit4] 2> 1501307 T7210 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> 1501313 T7210 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1501314 T7210 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1501314 T7210 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1501315 T7210 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1501316 T7210 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/cores [junit4] 2> 1501319 T7210 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 71DB1AE79D18C61D-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 1501319 T7210 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard0/cores/collection1/ [junit4] 2> 1501320 T7210 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1501321 T7236 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1501322 T7236 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> 1501322 T7236 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> 1501378 T7236 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1501421 T7236 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1501450 T7236 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1501451 T7236 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> 1501457 T7236 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1501611 T7236 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1501625 T7236 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1501627 T7236 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1501636 T7236 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1501638 T7236 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1501639 T7236 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1501642 T7236 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1501643 T7236 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1501643 T7236 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1501643 T7236 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1501644 T7236 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1501644 T7236 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> 1501644 T7236 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1501645 T7236 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> 1501645 T7236 x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ed73e07 [junit4] 2> 1501646 T7236 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 71DB1AE79D18C61D-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 1501646 T7236 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 71DB1AE79D18C61D-001/tempDir-001/shard0/cores/collection1/data/index/ [junit4] 2> 1501646 T7236 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 71DB1AE79D18C61D-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1501647 T7236 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 71DB1AE79D18C61D-001/tempDir-001/shard0/cores/collection1/data/index [junit4] 2> 1501647 T7236 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=37.5087890625, floorSegmentMB=1.5625, forceMergeDeletesPctAllowed=28.389685894081047, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6283865442706992 [junit4] 2> 1501648 T7236 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60f95de9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10638dbc),segFN=segments_1,generation=1} [junit4] 2> 1501648 T7236 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1501665 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1501665 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1501665 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1501665 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1501666 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1501666 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1501666 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1501667 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1501667 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1501667 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1501667 T7236 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1501667 T7236 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1501673 T7236 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1501677 T7236 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1501680 T7236 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1501681 T7236 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1501690 T7236 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1501693 T7236 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> 1501693 T7236 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1501694 T7236 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1501697 T7236 x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1501697 T7236 x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1501698 T7236 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1501698 T7236 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1501704 T7236 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=89.6259765625, floorSegmentMB=1.314453125, forceMergeDeletesPctAllowed=14.506972958832876, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1501704 T7236 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60f95de9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10638dbc),segFN=segments_1,generation=1} [junit4] 2> 1501704 T7236 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1501704 T7236 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@771ac5f3[collection1] main [junit4] 2> 1501705 T7236 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1501705 T7236 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1501705 T7236 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1501705 T7236 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1501715 T7236 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1501770 T7236 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1501771 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1501772 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1501772 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1501772 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1501773 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1501774 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1501774 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1501775 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1501775 T7237 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1501776 T7237 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@771ac5f3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1501777 T7236 x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1501779 T7210 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1501780 T7210 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1501781 T7210 oas.SolrTestCaseJ4.setUp ###Starting test [junit4] 2> ASYNC NEW_CORE C6837 name=collection1 org.apache.solr.core.SolrCore@4498ee9c [junit4] 2> 1501787 T7214 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1501479684446093312)} 0 2 [junit4] 2> ASYNC NEW_CORE C6838 name=collection1 org.apache.solr.core.SolrCore@673515c [junit4] 2> 1501796 T7228 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1501479684455530496)} 0 2 [junit4] 2> 1501800 T7215 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1501479684460773376)]} 0 1 [junit4] 2> 1501806 T7229 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1501479684467064832)]} 0 1 [junit4] 2> 1501810 T7216 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1501479684471259136)]} 0 1 [junit4] 2> 1501814 T7230 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1501479684476502016)]} 0 0 [junit4] 2> 1501817 T7217 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1501479684479647744)]} 0 0 [junit4] 2> 1501823 T7231 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1501479684484890624)]} 0 1 [junit4] 2> 1501827 T7218 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1501479684490133504)]} 0 0 [junit4] 2> 1501832 T7232 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1501479684494327808)]} 0 2 [junit4] 2> 1501836 T7219 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1501479684498522112)]} 0 1 [junit4] 2> 1501841 T7233 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1501479684503764992)]} 0 1 [junit4] 2> 1501844 T7214 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1501479684506910720)]} 0 1 [junit4] 2> 1501850 T7228 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1501479684514250752)]} 0 0 [junit4] 2> 1501859 T7215 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1501479684522639360)]} 0 1 [junit4] 2> 1501870 T7229 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1501479684534173696)]} 0 1 [junit4] 2> 1501874 T7216 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1501479684539416576)]} 0 0 [junit4] 2> 1501877 T7230 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1501479684542562304)]} 0 0 [junit4] 2> 1501879 T7217 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1501479684544659456)]} 0 0 [junit4] 2> 1501882 T7231 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1501479684547805184)]} 0 0 [junit4] 2> 1501886 T7218 x:collection1 C6837 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1501893 T7218 x:collection1 C6837 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ea1cd59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c512a1a),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ea1cd59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c512a1a),segFN=segments_2,generation=2} [junit4] 2> 1501894 T7218 x:collection1 C6837 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1501897 T7218 x:collection1 C6837 oass.SolrIndexSearcher.<init> Opening Searcher@580ab74a[collection1] main [junit4] 2> 1501897 T7218 x:collection1 C6837 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1501899 T7223 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@580ab74a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)))} [junit4] 2> 1501900 T7218 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 14 [junit4] 2> 1501902 T7232 x:collection1 C6838 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1501921 T7232 x:collection1 C6838 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60f95de9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10638dbc),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60f95de9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10638dbc),segFN=segments_2,generation=2} [junit4] 2> 1501923 T7232 x:collection1 C6838 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1501927 T7232 x:collection1 C6838 oass.SolrIndexSearcher.<init> Opening Searcher@6933e6c8[collection1] main [junit4] 2> 1501928 T7232 x:collection1 C6838 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1501932 T7237 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6933e6c8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)))} [junit4] 2> 1501932 T7232 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 30 [junit4] 2> 1501958 T7219 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1501479684623302656)]} 0 4 [junit4] 2> 1501964 T7233 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1501479684630642688)]} 0 3 [junit4] 2> 1501971 T7214 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1501479684640079872)]} 0 1 [junit4] 2> 1501976 T7228 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1501479684645322752)]} 0 1 [junit4] 2> 1501979 T7215 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1501479684649517056)]} 0 0 [junit4] 2> 1501983 T7229 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1501479684652662784)]} 0 1 [junit4] 2> 1501986 T7216 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1501479684655808512)]} 0 1 [junit4] 2> 1501988 T7230 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1501479684658954240)]} 0 0 [junit4] 2> 1501992 T7217 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1501479684662099968)]} 0 1 [junit4] 2> 1501995 T7231 x:collection1 C6838 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1501479684666294272)]} 0 0 [junit4] 2> 1501999 T7218 x:collection1 C6837 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1501479684669440000)]} 0 1 [junit4] 2> ASYNC NEW_CORE C6839 name=collection1 org.apache.solr.core.SolrCore@673515c [junit4] 2> 1502010 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1501479684673634304)]} 0 9 [junit4] 2> ASYNC NEW_CORE C6840 name=collection1 org.apache.solr.core.SolrCore@4498ee9c [junit4] 2> 1502014 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1501479684685168640)]} 0 1 [junit4] 2> 1502020 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1501479684692508672)]} 0 0 [junit4] 2> 1502034 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1501479684706140160)]} 0 1 [junit4] 2> 1502037 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1501479684710334464)]} 0 0 [junit4] 2> 1502040 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1501479684713480192)]} 0 0 [junit4] 2> 1502047 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1501479684720820224)]} 0 0 [junit4] 2> 1502055 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1501479684729208832)]} 0 0 [junit4] 2> 1502058 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1501479684732354560)]} 0 1 [junit4] 2> 1502064 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1501479684738646016)]} 0 0 [junit4] 2> 1502067 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1501479684741791744)]} 0 0 [junit4] 2> 1502070 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1501479684744937472)]} 0 1 [junit4] 2> 1502073 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1501479684748083200)]} 0 0 [junit4] 2> 1502076 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1501479684751228928)]} 0 0 [junit4] 2> 1502079 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1501479684754374656)]} 0 0 [junit4] 2> 1502084 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1501479684758568960)]} 0 1 [junit4] 2> 1502086 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1501479684761714688)]} 0 0 [junit4] 2> 1502091 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1501479684766957568)]} 0 0 [junit4] 2> 1502095 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1501479684770103296)]} 0 0 [junit4] 2> 1502097 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1501479684773249024)]} 0 0 [junit4] 2> 1502100 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1501479684775346176)]} 0 0 [junit4] 2> 1502103 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1501479684778491904)]} 0 1 [junit4] 2> 1502105 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1501479684781637632)]} 0 0 [junit4] 2> 1502109 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1501479684784783360)]} 0 1 [junit4] 2> 1502111 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1501479684787929088)]} 0 0 [junit4] 2> 1502114 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1501479684791074816)]} 0 0 [junit4] 2> 1502117 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1501479684794220544)]} 0 0 [junit4] 2> 1502120 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1501479684797366272)]} 0 1 [junit4] 2> 1502123 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1501479684800512000)]} 0 0 [junit4] 2> 1502127 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1501479684803657728)]} 0 1 [junit4] 2> 1502140 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1501479684817289216)]} 0 1 [junit4] 2> 1502148 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1501479684826726400)]} 0 0 [junit4] 2> 1502151 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1501479684829872128)]} 0 0 [junit4] 2> 1502153 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1501479684831969280)]} 0 0 [junit4] 2> 1502156 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1501479684835115008)]} 0 0 [junit4] 2> 1502159 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1501479684838260736)]} 0 0 [junit4] 2> 1502163 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1501479684841406464)]} 0 1 [junit4] 2> 1502165 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1501479684844552192)]} 0 0 [junit4] 2> 1502168 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1501479684847697920)]} 0 0 [junit4] 2> 1502171 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1501479684850843648)]} 0 1 [junit4] 2> 1502173 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1501479684852940800)]} 0 0 [junit4] 2> 1502175 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1501479684855037952)]} 0 0 [junit4] 2> 1502178 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1501479684858183680)]} 0 0 [junit4] 2> 1502181 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1501479684861329408)]} 0 0 [junit4] 2> 1502183 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1501479684863426560)]} 0 0 [junit4] 2> 1502186 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1501479684866572288)]} 0 1 [junit4] 2> 1502192 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1501479684871815168)]} 0 2 [junit4] 2> 1502195 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1501479684876009472)]} 0 0 [junit4] 2> 1502197 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1501479684878106624)]} 0 0 [junit4] 2> 1502199 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1501479684880203776)]} 0 0 [junit4] 2> 1502202 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1501479684882300928)]} 0 1 [junit4] 2> 1502205 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1501479684886495232)]} 0 0 [junit4] 2> 1502207 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1501479684888592384)]} 0 0 [junit4] 2> 1502212 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1501479684892786688)]} 0 1 [junit4] 2> 1502221 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1501479684903272448)]} 0 0 [junit4] 2> 1502224 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1501479684906418176)]} 0 0 [junit4] 2> 1502227 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1501479684909563904)]} 0 1 [junit4] 2> 1502229 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1501479684911661056)]} 0 0 [junit4] 2> 1502232 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1501479684913758208)]} 0 1 [junit4] 2> 1502234 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1501479684916903936)]} 0 0 [junit4] 2> 1502236 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1501479684919001088)]} 0 0 [junit4] 2> 1502239 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1501479684922146816)]} 0 0 [junit4] 2> 1502241 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1501479684924243968)]} 0 0 [junit4] 2> 1502244 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1501479684927389696)]} 0 0 [junit4] 2> 1502247 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1501479684929486848)]} 0 1 [junit4] 2> 1502249 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1501479684932632576)]} 0 0 [junit4] 2> 1502251 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1501479684934729728)]} 0 0 [junit4] 2> 1502254 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1501479684936826880)]} 0 1 [junit4] 2> 1502256 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1501479684939972608)]} 0 0 [junit4] 2> 1502258 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1501479684942069760)]} 0 0 [junit4] 2> 1502261 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1501479684945215488)]} 0 0 [junit4] 2> 1502263 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1501479684947312640)]} 0 0 [junit4] 2> 1502265 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1501479684949409792)]} 0 0 [junit4] 2> 1502268 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1501479684952555520)]} 0 1 [junit4] 2> 1502270 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1501479684954652672)]} 0 0 [junit4] 2> 1502273 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1501479684957798400)]} 0 0 [junit4] 2> 1502275 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1501479684959895552)]} 0 0 [junit4] 2> 1502278 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1501479684961992704)]} 0 1 [junit4] 2> 1502280 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1501479684965138432)]} 0 0 [junit4] 2> 1502282 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1501479684967235584)]} 0 0 [junit4] 2> 1502285 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1501479684969332736)]} 0 1 [junit4] 2> 1502287 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1501479684972478464)]} 0 0 [junit4] 2> 1502289 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1501479684974575616)]} 0 0 [junit4] 2> 1502291 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1501479684976672768)]} 0 0 [junit4] 2> 1502294 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1501479684979818496)]} 0 1 [junit4] 2> 1502296 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1501479684981915648)]} 0 0 [junit4] 2> 1502299 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1501479684984012800)]} 0 1 [junit4] 2> 1502301 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1501479684987158528)]} 0 0 [junit4] 2> 1502306 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1501479684992401408)]} 0 0 [junit4] 2> 1502309 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1501479684995547136)]} 0 0 [junit4] 2> 1502311 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1501479684997644288)]} 0 0 [junit4] 2> 1502315 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1501479685001838592)]} 0 0 [junit4] 2> 1502322 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1501479685009178624)]} 0 1 [junit4] 2> 1502324 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1501479685011275776)]} 0 0 [junit4] 2> 1502327 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1501479685014421504)]} 0 0 [junit4] 2> 1502329 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1501479685016518656)]} 0 0 [junit4] 2> 1502332 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1501479685019664384)]} 0 1 [junit4] 2> 1502334 T7219 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1501479685021761536)]} 0 0 [junit4] 2> 1502337 T7233 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1501479685024907264)]} 0 0 [junit4] 2> 1502340 T7214 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1501479685028052992)]} 0 0 [junit4] 2> 1502343 T7228 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1501479685030150144)]} 0 1 [junit4] 2> 1502345 T7215 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1501479685033295872)]} 0 0 [junit4] 2> 1502348 T7229 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1501479685036441600)]} 0 0 [junit4] 2> 1502350 T7216 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1501479685038538752)]} 0 0 [junit4] 2> 1502352 T7230 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1501479685040635904)]} 0 0 [junit4] 2> 1502355 T7217 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1501479685042733056)]} 0 1 [junit4] 2> 1502357 T7231 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1501479685045878784)]} 0 0 [junit4] 2> 1502359 T7218 x:collection1 C6840 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1502379 T7218 x:collection1 C6840 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ea1cd59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c512a1a),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ea1cd59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c512a1a),segFN=segments_3,generation=3} [junit4] 2> 1502379 T7218 x:collection1 C6840 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1502380 T7218 x:collection1 C6840 oass.SolrIndexSearcher.<init> Opening Searcher@122d0755[collection1] main [junit4] 2> 1502380 T7218 x:collection1 C6840 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1502381 T7223 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@122d0755[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9) Uninverting(_1(6.0.0):C59)))} [junit4] 2> 1502382 T7218 x:collection1 C6840 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 23 [junit4] 2> 1502388 T7232 x:collection1 C6839 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1502410 T7232 x:collection1 C6839 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60f95de9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10638dbc),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60f95de9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10638dbc),segFN=segments_3,generation=3} [junit4] 2> 1502410 T7232 x:collection1 C6839 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1502411 T7232 x:collection1 C6839 oass.SolrIndexSearcher.<init> Opening Searcher@5c394b4b[collection1] main [junit4] 2> 1502411 T7232 x:collection1 C6839 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1502412 T7237 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5c394b4b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9) Uninverting(_1(6.0.0):C59)))} [junit4] 2> 1502413 T7232 x:collection1 C6839 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 25 [junit4] 2> 1502416 T7219 x:collection1 C6840 oasc.SolrException.log Ignoring exception matching parameter cannot be negative [junit4] 2> 1502416 T7219 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=1 [junit4] 2> 1502418 T7214 x:collection1 C6840 oasc.SolrException.log Ignoring exception matching parameter cannot be negative [junit4] 2> 1502418 T7214 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 1502424 T7215 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 1502436 T7228 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:62360//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1431922612306&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1502441 T7229 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612306&ids=121,1,101,112,114,16,17,106,7,118&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:62360//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 1502446 T7233 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:62360//collection1|[ff01::114]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=17 [junit4] 2> 1502449 T7216 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 1502455 T7231 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:62360//collection1&rows=10&version=2&q=*:*&NOW=1431922612329&isShard=true&wt=javabin} hits=68 status=0 QTime=1 [junit4] 2> 1502458 T7232 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612329&ids=143,135,113,124,125,103,8,108,131,142&isShard=true&shard.url=http://127.0.0.1:62360//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1502470 T7230 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:62360//collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=18 [junit4] 2> 1502474 T7217 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 1502479 T7229 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:62360//collection1&rows=10&version=2&q=*:*&NOW=1431922612353&isShard=true&wt=javabin} hits=68 status=0 QTime=1 [junit4] 2> 1502482 T7233 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612353&ids=1,122,100,124,136,5,148,106,141,142&isShard=true&shard.url=http://127.0.0.1:62360//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1502487 T7228 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:62360//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=11 [junit4] 2> 1502491 T7218 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 1502506 T7232 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:62360//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1431922612378&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1502512 T7230 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612378&ids=12,145,123,113,125,147,16,6,128,130&isShard=true&shard.url=http://127.0.0.1:62360//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 1502517 T7231 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:62360//collection1|[ff01::083]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=17 [junit4] 2> 1502520 T7219 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 1502524 T7233 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= 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:62360//collection1&rows=10&version=2&q=*:*&NOW=1431922612399&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1502527 T7228 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612399&ids=132,143,123,112,136,114,127,118,129,141&isShard=true&shard.url=http://127.0.0.1:62360//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1502529 T7229 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:62360//collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7 [junit4] 2> 1502531 T7214 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 1502537 T7230 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:62360//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1431922612411&isShard=true&wt=javabin} hits=68 status=0 QTime=1 [junit4] 2> 1502540 T7231 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612411&ids=122,101,125,104,105,6,128,119,142,120&isShard=true&shard.url=http://127.0.0.1:62360//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 1502542 T7232 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:62360//collection1|[ff01::213]:33332/&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=8 [junit4] 2> 1502544 T7215 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 1502550 T7228 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= 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:62360//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1431922612424&isShard=true&wt=javabin} hits=68 status=0 QTime=1 [junit4] 2> 1502553 T7229 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612424&ids=110,123,112,136,125,15,116,106,140,131&isShard=true&shard.url=http://127.0.0.1:62360//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 1502554 T7233 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:62360//collection1|[ff01::213]:33332/&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7 [junit4] 2> 1502558 T7216 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 1502562 T7231 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= 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:62360//collection1&rows=10&version=2&q=*:*&NOW=1431922612438&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1502565 T7232 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612438&ids=100,134,145,14,147,4,104,149,129,108&isShard=true&shard.url=http://127.0.0.1:62360//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1502566 T7230 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:62360//collection1&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 1502569 T7217 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 1502576 T7229 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:62360//collection1&rows=10&version=2&q=*:*&NOW=1431922612451&isShard=true&wt=javabin} hits=68 status=0 QTime=1 [junit4] 2> 1502578 T7233 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612451&ids=143,132,122,12,146,16,17,138,118,131&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:62360//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1502579 T7228 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:62360//collection1&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 1502582 T7218 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 1502586 T7232 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:62360//collection1&rows=10&version=2&q=*:*&NOW=1431922612461&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1502589 T7230 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1431922612461&ids=11,1,111,101,145,113,127,1001,9,109&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:62360//collection1&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 1502590 T7231 x:collection1 C6839 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:62360//collection1&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 [junit4] 2> 1502592 T7219 x:collection1 C6840 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version [...truncated too long message...] r.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard1/cores/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard1/cores/collection1/data/index;done=false>>] [junit4] 2> 1647876 T7210 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 71DB1AE79D18C61D-001/tempDir-001/shard1/cores/collection1/data/index [junit4] 2> 1647878 T7210 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@2ce7e8d3{/,null,UNAVAILABLE} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=71DB1AE79D18C61D -Dtests.slow=true -Dtests.locale=ar_KW -Dtests.timezone=America/Boise -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 148s J0 | TestDistributedSearch.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:62418//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:636) [junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:436) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:198) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:178) [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([71DB1AE79D18C61D:F98F253D33E4ABE5]: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> 1647919 T7210 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 71DB1AE79D18C61D-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50), sim=DefaultSimilarity, locale=ar_KW, timezone=America/Boise [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=3,threads=1,free=100358288,total=289247232 [junit4] 2> NOTE: All tests run in this JVM: [DistributedQueryComponentCustomSortTest, ShardSplitTest, TestCloudSchemaless, SolrIndexSplitterTest, OutputWriterTest, RankQueryTest, TestGroupingSearch, TestFastLRUCache, TestConfigReload, TestUpdate, DistributedFacetPivotSmallAdvancedTest, TestManagedSchemaFieldTypeResource, IndexSchemaRuntimeFieldTest, PathHierarchyTokenizerFactoryTest, BasicDistributedZkTest, TestExactSharedStatsCache, TestRestManager, AsyncMigrateRouteKeyTest, HdfsChaosMonkeySafeLeaderTest, AlternateDirectoryTest, TestCustomSort, TestXIncludeConfig, TestDynamicLoading, MergeStrategyTest, SortByFunctionTest, TestSerializedLuceneMatchVersion, AnalyticsMergeStrategyTest, TestExtendedDismaxParser, NumericFieldsTest, TestReplicationHandlerBackup, TestCSVResponseWriter, ZkCLITest, TestDocBasedVersionConstraints, TestClassNameShortening, TestWriterPerf, SimpleMLTQParserTest, ScriptEngineTest, UniqFieldsUpdateProcessorFactoryTest, HdfsSyncSliceTest, TestDocumentBuilder, TestOrdValues, DOMUtilTest, DistributedFacetPivotLargeTest, TestChildDocTransformer, TestBinaryResponseWriter, OverseerTest, TestImplicitCoreProperties, TestSchemaResource, OverseerRolesTest, LeaderInitiatedRecoveryOnCommitTest, AnalysisAfterCoreReloadTest, TestFieldTypeCollectionResource, SolrCoreTest, QueryElevationComponentTest, SuggesterWFSTTest, ResponseHeaderTest, TestElisionMultitermQuery, TestSchemaNameResource, DocExpirationUpdateProcessorFactoryTest, TestArbitraryIndexDir, UUIDFieldTest, CursorPagingTest, TestWordDelimiterFilterFactory, TestSimpleTrackingShardHandler, PreAnalyzedFieldTest, TestSweetSpotSimilarityFactory, TestFaceting, TestStressLucene, ChaosMonkeyNothingIsSafeTest, TestCoreContainer, SliceStateTest, TestSolrQueryParserDefaultOperatorResource, RecoveryZkTest, TestHighlightDedupGrouping, SolrCoreCheckLockOnStartupTest, ShardRoutingTest, TestPhraseSuggestions, ResponseLogComponentTest, TestBulkSchemaConcurrent, ZkControllerTest, TestExpandComponent, DistribCursorPagingTest, DocValuesMultiTest, SimpleFacetsTest, ChangedSchemaMergeTest, RuleEngineTest, TestReqParamsAPI, DocumentAnalysisRequestHandlerTest, TestAnalyzedSuggestions, TestReplicationHandler, HttpPartitionTest, TestTolerantSearch, ParsingFieldUpdateProcessorsTest, TestDefaultStatsCache, TestExceedMaxTermLength, SuggesterTest, TestStressReorder, TimeZoneUtilsTest, TestDistributedSearch] [junit4] Completed [242/490] on J0 in 148.14s, 1 test, 1 error <<< FAILURES! [...truncated 781 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: 490 suites, 1958 tests, 1 error, 62 ignored (24 assumptions) Total time: 72 minutes 14 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
