Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2215/ Java: 64bit/jdk1.7.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:61465/aq_vcz/jo/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.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223) 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:61465/aq_vcz/jo/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.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223) 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([96A9A2AE4ED5DA2C:1EFD9D74E029B7D4]: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:1080) at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1042) at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:915) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) 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 10394 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/init-core-data-001 [junit4] 2> 1874016 T7704 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 1874016 T7704 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /aq_vcz/jo [junit4] 2> 1874409 T7704 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores/collection1 [junit4] 2> 1874414 T7704 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 1874419 T7704 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@33ab9869{/aq_vcz/jo,null,AVAILABLE} [junit4] 2> 1874420 T7704 oejs.AbstractConnector.doStart Started ServerConnector@46a32fff{HTTP/1.1}{127.0.0.1:61426} [junit4] 2> 1874420 T7704 oejs.Server.doStart Started @1878089ms [junit4] 2> 1874420 T7704 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores, hostContext=/aq_vcz/jo, hostPort=61426} [junit4] 2> 1874421 T7704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4d885088 [junit4] 2> 1874421 T7704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/' [junit4] 2> 1874455 T7704 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/solr.xml [junit4] 2> 1874487 T7704 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores [junit4] 2> 1874488 T7704 oasc.CoreContainer.<init> New CoreContainer 1717426939 [junit4] 2> 1874488 T7704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/] [junit4] 2> 1874488 T7704 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/lib [junit4] 2> 1874489 T7704 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/lib). [junit4] 2> 1874511 T7704 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> 1874516 T7704 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1874518 T7704 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1874518 T7704 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1874518 T7704 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1874523 T7704 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores [junit4] 2> 1874525 T7704 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores/collection1/, loadOnStartup=true, config=solrconfig.xml, transient=false, schema=schema.xml, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores/collection1, configSet=collection1, name=collection1, dataDir=data/} [junit4] 2> 1874525 T7704 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores/collection1/ [junit4] 2> 1874525 T7704 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1874526 T7716 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1874527 T7716 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1874527 T7716 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1874584 T7716 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1874613 T7716 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1874687 T7716 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1874690 T7716 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1874708 T7716 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1875023 T7716 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1875033 T7716 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1875035 T7716 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1875042 T7716 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1875046 T7716 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1875050 T7716 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1875050 T7716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1875051 T7716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1875051 T7716 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1875051 T7716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1875051 T7716 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1875052 T7716 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1 [junit4] 2> 1875052 T7716 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1875052 T7716 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/], dataDir=[null] [junit4] 2> 1875052 T7716 x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d8c8631 [junit4] 2> 1875053 T7716 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores/collection1/data [junit4] 2> 1875053 T7716 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores/collection1/data/index/ [junit4] 2> 1875053 T7716 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1875053 T7716 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 1875053 T7716 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=98.2373046875, floorSegmentMB=1.58984375, forceMergeDeletesPctAllowed=1.168386223382134, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1875054 T7716 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5cf3a60c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8d81),segFN=segments_1,generation=1} [junit4] 2> 1875054 T7716 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1875060 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1875061 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1875061 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1875061 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1875061 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1875061 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1875061 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1875062 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1875062 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1875062 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1875062 T7716 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1875062 T7716 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1875073 T7716 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1875075 T7716 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1875077 T7716 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1875079 T7716 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1875084 T7716 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1875086 T7716 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /search-facet-invariants,/replication,spellCheckCompRH1,dismax,/schema,/update/json,lazy,/get,spellCheckCompRH,/admin/ping,/update/json/docs,defaults,tvrh,spellCheckWithWordbreak,/update/csv,/update,/mlt,spellCheckCompRH_Direct,/config,/admin/segments,mltrh,/search-facet-def,/admin/threads,/admin/luke,/debug/dump,/admin/mbeans,/admin/plugins,/terms,/admin/logging,/admin/file,/admin/properties,/admin/system,standard,spellCheckWithWordbreak_Direct,mock [junit4] 2> 1875088 T7716 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1875089 T7716 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1875090 T7716 x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1875090 T7716 x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1875091 T7716 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1875091 T7716 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1875092 T7716 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7607235755784725] [junit4] 2> 1875092 T7716 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5cf3a60c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8d81),segFN=segments_1,generation=1} [junit4] 2> 1875092 T7716 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1875092 T7716 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@5bae970c[collection1] main [junit4] 2> 1875093 T7716 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1875093 T7716 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1875093 T7716 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1875093 T7716 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1875101 T7716 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1875137 T7716 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1875137 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1875138 T7716 x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1875139 T7704 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1875139 T7704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1875139 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1875140 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1875140 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1875141 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1875143 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1875144 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1875145 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1875147 T7717 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1875149 T7717 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5bae970c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1875508 T7704 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 1875511 T7704 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 1875516 T7704 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@73a9e8c5{/aq_vcz/jo,null,AVAILABLE} [junit4] 2> 1875517 T7704 oejs.AbstractConnector.doStart Started ServerConnector@559595e1{HTTP/1.1}{127.0.0.1:61427} [junit4] 2> 1875517 T7704 oejs.Server.doStart Started @1879186ms [junit4] 2> 1875517 T7704 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores, hostContext=/aq_vcz/jo, hostPort=61427} [junit4] 2> 1875517 T7704 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4d885088 [junit4] 2> 1875518 T7704 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/' [junit4] 2> 1875552 T7704 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/solr.xml [junit4] 2> 1875587 T7704 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores [junit4] 2> 1875587 T7704 oasc.CoreContainer.<init> New CoreContainer 383480675 [junit4] 2> 1875588 T7704 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/] [junit4] 2> 1875588 T7704 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/lib [junit4] 2> 1875589 T7704 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/lib). [junit4] 2> 1875600 T7704 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> 1875604 T7704 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1875605 T7704 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1875605 T7704 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1875605 T7704 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1875608 T7704 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores [junit4] 2> 1875609 T7704 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {schema=schema.xml, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores/collection1/, config=solrconfig.xml, transient=false, dataDir=data/, loadOnStartup=true, name=collection1, configSet=collection1, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores/collection1} [junit4] 2> 1875609 T7704 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores/collection1/ [junit4] 2> 1875609 T7704 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1875610 T7730 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/' [junit4] 2> 1875611 T7730 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 1875611 T7730 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 1875650 T7730 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1875685 T7730 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0 [junit4] 2> 1875735 T7730 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1875737 T7730 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 1875744 T7730 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1876043 T7730 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1876053 T7730 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1876055 T7730 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1876062 T7730 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1876065 T7730 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1876068 T7730 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1876069 T7730 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1876069 T7730 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1876070 T7730 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1876070 T7730 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1876070 T7730 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1876070 T7730 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1 [junit4] 2> 1876070 T7730 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1876071 T7730 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/], dataDir=[null] [junit4] 2> 1876071 T7730 x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d8c8631 [junit4] 2> 1876071 T7730 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 1876072 T7730 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores/collection1/data/index/ [junit4] 2> 1876072 T7730 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1876072 T7730 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001/tempDir-001/shard0/cores/collection1/data/index [junit4] 2> 1876072 T7730 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=98.2373046875, floorSegmentMB=1.58984375, forceMergeDeletesPctAllowed=1.168386223382134, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1876073 T7730 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b592df lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2cbd70e3),segFN=segments_1,generation=1} [junit4] 2> 1876073 T7730 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1876081 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1876081 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1876081 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1876081 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 1876082 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1876082 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1876082 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 1876082 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 1876082 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1876083 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1876083 T7730 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1876083 T7730 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1876094 T7730 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1876096 T7730 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1876098 T7730 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1876100 T7730 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1876108 T7730 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 1876115 T7730 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /search-facet-invariants,/replication,spellCheckCompRH1,dismax,/schema,/update/json,lazy,/get,spellCheckCompRH,/admin/ping,/update/json/docs,defaults,tvrh,spellCheckWithWordbreak,/update/csv,/update,/mlt,spellCheckCompRH_Direct,/config,/admin/segments,mltrh,/search-facet-def,/admin/threads,/admin/luke,/debug/dump,/admin/mbeans,/admin/plugins,/terms,/admin/logging,/admin/file,/admin/properties,/admin/system,standard,spellCheckWithWordbreak_Direct,mock [junit4] 2> 1876117 T7730 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 1876118 T7730 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1876119 T7730 x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1876120 T7730 x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 1876121 T7730 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1876121 T7730 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1876122 T7730 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7607235755784725] [junit4] 2> 1876123 T7730 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b592df lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2cbd70e3),segFN=segments_1,generation=1} [junit4] 2> 1876123 T7730 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1876124 T7730 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@42785d7b[collection1] main [junit4] 2> 1876124 T7730 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 1876124 T7730 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 1876124 T7730 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1876124 T7730 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 1876137 T7730 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1876171 T7730 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1876171 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 1876172 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 1876172 T7730 x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1876172 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 1876173 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 1876174 T7704 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 1876174 T7704 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1876174 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 1876176 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 1876176 T7704 oas.SolrTestCaseJ4.setUp ###Starting test [junit4] 2> 1876177 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 1876178 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 1876180 T7731 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 1876183 T7731 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@42785d7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> ASYNC NEW_CORE C2008 name=collection1 org.apache.solr.core.SolrCore@718b92e0 [junit4] 2> 1876185 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1500251191063347200)} 0 2 [junit4] 2> ASYNC NEW_CORE C2009 name=collection1 org.apache.solr.core.SolrCore@31b0b105 [junit4] 2> 1876193 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1500251191070687232)} 0 3 [junit4] 2> 1876199 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[1 (1500251191078027264)]} 0 3 [junit4] 2> 1876204 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[1 (1500251191083270144)]} 0 2 [junit4] 2> 1876209 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[2 (1500251191089561600)]} 0 1 [junit4] 2> 1876213 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[2 (1500251191093755904)]} 0 1 [junit4] 2> 1876216 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[3 (1500251191096901632)]} 0 1 [junit4] 2> 1876221 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[3 (1500251191102144512)]} 0 1 [junit4] 2> 1876226 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[4 (1500251191107387392)]} 0 1 [junit4] 2> 1876231 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[4 (1500251191112630272)]} 0 1 [junit4] 2> 1876236 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[5 (1500251191117873152)]} 0 1 [junit4] 2> 1876241 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[5 (1500251191123116032)]} 0 1 [junit4] 2> 1876246 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[6 (1500251191128358912)]} 0 1 [junit4] 2> 1876251 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[6 (1500251191133601792)]} 0 1 [junit4] 2> 1876255 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[7 (1500251191138844672)]} 0 1 [junit4] 2> 1876260 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[7 (1500251191143038976)]} 0 1 [junit4] 2> 1876264 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[8 (1500251191147233280)]} 0 0 [junit4] 2> 1876267 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[8 (1500251191151427584)]} 0 1 [junit4] 2> 1876271 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[9 (1500251191155621888)]} 0 1 [junit4] 2> 1876275 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[9 (1500251191159816192)]} 0 1 [junit4] 2> 1876278 T7712 x:collection1 C2008 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1876284 T7712 x:collection1 C2008 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5cf3a60c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8d81),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5cf3a60c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8d81),segFN=segments_2,generation=2} [junit4] 2> 1876286 T7712 x:collection1 C2008 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1876288 T7712 x:collection1 C2008 oass.SolrIndexSearcher.<init> Opening Searcher@2730c16f[collection1] main [junit4] 2> 1876289 T7712 x:collection1 C2008 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1876290 T7717 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2730c16f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C9)))} [junit4] 2> 1876291 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={commit=true&waitSearcher=true&softCommit=false&wt=javabin&version=2} {commit=} 0 13 [junit4] 2> 1876293 T7723 x:collection1 C2009 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1876302 T7723 x:collection1 C2009 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b592df lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2cbd70e3),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b592df lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2cbd70e3),segFN=segments_2,generation=2} [junit4] 2> 1876304 T7723 x:collection1 C2009 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 1876307 T7723 x:collection1 C2009 oass.SolrIndexSearcher.<init> Opening Searcher@1c072a1c[collection1] main [junit4] 2> 1876308 T7723 x:collection1 C2009 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1876309 T7731 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c072a1c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C9)))} [junit4] 2> 1876310 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={commit=true&waitSearcher=true&softCommit=false&wt=javabin&version=2} {commit=} 0 17 [junit4] 2> 1876316 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[10 (1500251191199662080)]} 0 4 [junit4] 2> 1876322 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[10 (1500251191205953536)]} 0 3 [junit4] 2> 1876326 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[11 (1500251191213293568)]} 0 1 [junit4] 2> 1876330 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[11 (1500251191217487872)]} 0 1 [junit4] 2> 1876334 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[12 (1500251191220633600)]} 0 1 [junit4] 2> 1876337 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[12 (1500251191224827904)]} 0 1 [junit4] 2> 1876340 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[13 (1500251191227973632)]} 0 1 [junit4] 2> 1876347 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[13 (1500251191233216512)]} 0 2 [junit4] 2> 1876351 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[1001 (1500251191239507968)]} 0 1 [junit4] 2> 1876355 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[1001 (1500251191243702272)]} 0 1 [junit4] 2> 1876359 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[14 (1500251191247896576)]} 0 0 [junit4] 2> 1876363 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[14 (1500251191251042304)]} 0 1 [junit4] 2> 1876366 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[15 (1500251191255236608)]} 0 0 [junit4] 2> 1876369 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[15 (1500251191258382336)]} 0 0 [junit4] 2> 1876373 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[16 (1500251191262576640)]} 0 0 [junit4] 2> 1876378 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[16 (1500251191266770944)]} 0 1 [junit4] 2> 1876381 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[17 (1500251191270965248)]} 0 1 [junit4] 2> 1876386 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[17 (1500251191275159552)]} 0 1 [junit4] 2> 1876391 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[100 (1500251191280402432)]} 0 1 [junit4] 2> 1876395 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[100 (1500251191285645312)]} 0 0 [junit4] 2> 1876400 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[101 (1500251191290888192)]} 0 1 [junit4] 2> 1876404 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[101 (1500251191294033920)]} 0 1 [junit4] 2> 1876408 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[102 (1500251191299276800)]} 0 1 [junit4] 2> 1876411 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[102 (1500251191302422528)]} 0 0 [junit4] 2> 1876416 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[103 (1500251191305568256)]} 0 2 [junit4] 2> 1876420 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[103 (1500251191310811136)]} 0 1 [junit4] 2> 1876424 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[104 (1500251191315005440)]} 0 1 [junit4] 2> 1876427 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[104 (1500251191319199744)]} 0 1 [junit4] 2> 1876431 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[105 (1500251191323394048)]} 0 1 [junit4] 2> 1876434 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[105 (1500251191326539776)]} 0 1 [junit4] 2> 1876438 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[106 (1500251191330734080)]} 0 0 [junit4] 2> 1876442 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[106 (1500251191334928384)]} 0 0 [junit4] 2> 1876445 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[107 (1500251191338074112)]} 0 0 [junit4] 2> 1876449 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[107 (1500251191341219840)]} 0 1 [junit4] 2> 1876452 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[108 (1500251191345414144)]} 0 0 [junit4] 2> 1876456 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[108 (1500251191349608448)]} 0 0 [junit4] 2> 1876459 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[109 (1500251191352754176)]} 0 0 [junit4] 2> 1876462 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[109 (1500251191355899904)]} 0 0 [junit4] 2> 1876465 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[110 (1500251191359045632)]} 0 0 [junit4] 2> 1876469 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[110 (1500251191363239936)]} 0 0 [junit4] 2> 1876472 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[111 (1500251191366385664)]} 0 0 [junit4] 2> 1876476 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[111 (1500251191370579968)]} 0 0 [junit4] 2> 1876479 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[112 (1500251191373725696)]} 0 0 [junit4] 2> 1876483 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[112 (1500251191376871424)]} 0 1 [junit4] 2> 1876486 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[113 (1500251191381065728)]} 0 1 [junit4] 2> 1876489 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[113 (1500251191384211456)]} 0 0 [junit4] 2> 1876493 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[114 (1500251191388405760)]} 0 0 [junit4] 2> 1876496 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[114 (1500251191391551488)]} 0 0 [junit4] 2> 1876500 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[115 (1500251191395745792)]} 0 0 [junit4] 2> 1876503 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[115 (1500251191398891520)]} 0 0 [junit4] 2> 1876506 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[116 (1500251191402037248)]} 0 0 [junit4] 2> 1876511 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[116 (1500251191406231552)]} 0 1 [junit4] 2> 1876514 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[117 (1500251191410425856)]} 0 0 [junit4] 2> 1876519 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[117 (1500251191415668736)]} 0 0 [junit4] 2> 1876524 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[118 (1500251191420911616)]} 0 0 [junit4] 2> 1876529 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[118 (1500251191426154496)]} 0 0 [junit4] 2> 1876538 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[119 (1500251191434543104)]} 0 1 [junit4] 2> 1876543 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[119 (1500251191440834560)]} 0 1 [junit4] 2> 1876551 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[120 (1500251191448174592)]} 0 1 [junit4] 2> 1876562 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[120 (1500251191459708928)]} 0 2 [junit4] 2> 1876570 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[121 (1500251191467048960)]} 0 2 [junit4] 2> 1876574 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[121 (1500251191473340416)]} 0 0 [junit4] 2> 1876579 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[122 (1500251191478583296)]} 0 0 [junit4] 2> 1876588 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[122 (1500251191486971904)]} 0 1 [junit4] 2> 1876594 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[123 (1500251191493263360)]} 0 1 [junit4] 2> 1876598 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[123 (1500251191498506240)]} 0 0 [junit4] 2> 1876603 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[124 (1500251191503749120)]} 0 0 [junit4] 2> 1876612 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[124 (1500251191512137728)]} 0 1 [junit4] 2> 1876619 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[125 (1500251191519477760)]} 0 2 [junit4] 2> 1876624 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[125 (1500251191524720640)]} 0 1 [junit4] 2> 1876628 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[126 (1500251191529963520)]} 0 1 [junit4] 2> 1876633 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[126 (1500251191534157824)]} 0 1 [junit4] 2> 1876638 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[127 (1500251191539400704)]} 0 1 [junit4] 2> 1876646 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[127 (1500251191547789312)]} 0 1 [junit4] 2> 1876651 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[128 (1500251191553032192)]} 0 1 [junit4] 2> 1876657 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[128 (1500251191559323648)]} 0 2 [junit4] 2> 1876660 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[129 (1500251191563517952)]} 0 0 [junit4] 2> 1876666 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[129 (1500251191567712256)]} 0 2 [junit4] 2> 1876672 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[130 (1500251191575052288)]} 0 1 [junit4] 2> 1876679 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[130 (1500251191583440896)]} 0 1 [junit4] 2> 1876687 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[131 (1500251191589732352)]} 0 2 [junit4] 2> 1876692 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[131 (1500251191596023808)]} 0 1 [junit4] 2> 1876697 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[132 (1500251191601266688)]} 0 1 [junit4] 2> 1876700 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[132 (1500251191605460992)]} 0 0 [junit4] 2> 1876705 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[133 (1500251191610703872)]} 0 0 [junit4] 2> 1876709 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[133 (1500251191614898176)]} 0 0 [junit4] 2> 1876719 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[134 (1500251191624335360)]} 0 1 [junit4] 2> 1876723 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[134 (1500251191628529664)]} 0 1 [junit4] 2> 1876727 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[135 (1500251191632723968)]} 0 1 [junit4] 2> 1876731 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[135 (1500251191636918272)]} 0 1 [junit4] 2> 1876735 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[136 (1500251191641112576)]} 0 1 [junit4] 2> 1876740 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[136 (1500251191646355456)]} 0 1 [junit4] 2> 1876744 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[137 (1500251191650549760)]} 0 1 [junit4] 2> 1876748 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[137 (1500251191654744064)]} 0 1 [junit4] 2> 1876756 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[138 (1500251191662084096)]} 0 3 [junit4] 2> 1876763 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[138 (1500251191670472704)]} 0 1 [junit4] 2> 1876772 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[139 (1500251191679909888)]} 0 2 [junit4] 2> 1876778 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[139 (1500251191686201344)]} 0 1 [junit4] 2> 1876783 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[140 (1500251191691444224)]} 0 2 [junit4] 2> 1876789 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[140 (1500251191697735680)]} 0 2 [junit4] 2> 1876793 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[141 (1500251191701929984)]} 0 1 [junit4] 2> 1876797 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[141 (1500251191706124288)]} 0 1 [junit4] 2> 1876802 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[142 (1500251191712415744)]} 0 1 [junit4] 2> 1876807 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[142 (1500251191716610048)]} 0 2 [junit4] 2> 1876811 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[143 (1500251191721852928)]} 0 0 [junit4] 2> 1876815 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[143 (1500251191724998656)]} 0 1 [junit4] 2> 1876819 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[144 (1500251191729192960)]} 0 1 [junit4] 2> 1876823 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[144 (1500251191733387264)]} 0 1 [junit4] 2> 1876828 T7713 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[145 (1500251191738630144)]} 0 2 [junit4] 2> 1876831 T7727 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[145 (1500251191742824448)]} 0 0 [junit4] 2> 1876835 T7708 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[146 (1500251191745970176)]} 0 1 [junit4] 2> 1876838 T7722 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[146 (1500251191750164480)]} 0 0 [junit4] 2> 1876842 T7709 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[147 (1500251191753310208)]} 0 1 [junit4] 2> 1876846 T7725 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[147 (1500251191757504512)]} 0 1 [junit4] 2> 1876851 T7710 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[148 (1500251191763795968)]} 0 1 [junit4] 2> 1876855 T7724 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[148 (1500251191767990272)]} 0 0 [junit4] 2> 1876859 T7711 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[149 (1500251191772184576)]} 0 1 [junit4] 2> 1876863 T7726 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={wt=javabin&version=2} {add=[149 (1500251191776378880)]} 0 1 [junit4] 2> 1876866 T7712 x:collection1 C2008 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1876902 T7712 x:collection1 C2008 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5cf3a60c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8d81),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5cf3a60c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a8d81),segFN=segments_3,generation=3} [junit4] 2> 1876902 T7712 x:collection1 C2008 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1876903 T7712 x:collection1 C2008 oass.SolrIndexSearcher.<init> Opening Searcher@4c3bc6ab[collection1] main [junit4] 2> 1876904 T7712 x:collection1 C2008 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1876906 T7717 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4c3bc6ab[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C9) Uninverting(_1(5.2.0):C59)))} [junit4] 2> 1876907 T7712 x:collection1 C2008 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={commit=true&waitSearcher=true&softCommit=false&wt=javabin&version=2} {commit=} 0 41 [junit4] 2> 1876910 T7723 x:collection1 C2009 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1876939 T7723 x:collection1 C2009 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b592df lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2cbd70e3),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4b592df lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2cbd70e3),segFN=segments_3,generation=3} [junit4] 2> 1876940 T7723 x:collection1 C2009 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 1876943 T7723 x:collection1 C2009 oass.SolrIndexSearcher.<init> Opening Searcher@45ffba24[collection1] main [junit4] 2> 1876944 T7723 x:collection1 C2009 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 1876945 T7731 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@45ffba24[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):C9) Uninverting(_1(5.2.0):C59)))} [junit4] 2> 1876947 T7723 x:collection1 C2009 oasup.LogUpdateProcessor.finish [collection1] webapp=/aq_vcz/jo path=/update params={commit=true&waitSearcher=true&softCommit=false&wt=javabin&version=2} {commit=} 0 37 [junit4] 2> 1876952 T7713 x:collection1 C2008 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: 'start' parameter cannot be negative [junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:228) [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:229) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2047) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) [junit4] 2> at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:497) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 1876953 T7713 x:collection1 C2008 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={q=*&wt=javabin&version=2&distrib=false&start=-1} status=400 QTime=2 [junit4] 2> 1876957 T7708 x:collection1 C2008 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: 'rows' parameter cannot be negative [junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:222) [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:229) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2047) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) [junit4] 2> at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:497) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 1876958 T7708 x:collection1 C2008 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={q=*&rows=-1&wt=javabin&version=2&distrib=false&start=0} status=400 QTime=1 [junit4] 2> 1876963 T7709 x:collection1 C2008 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={sort=n_ti1+desc&q=*:*&wt=javabin&version=2&distrib=false} hits=68 status=0 QTime=0 [junit4] 2> 1876979 T7722 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={isShard=true&fl=id&sort=n_ti1+desc&q=*:*&shards.purpose=4&NOW=1430751029861&fsv=true&rows=10&wt=javabin&version=2&shard.url=[ff01::083]:33332/aq_vcz/jo|[ff01::213]:33332/aq_vcz/jo|http://127.0.0.1:61427/aq_vcz/jo/collection1&distrib=false&start=0} hits=68 status=0 QTime=0 [junit4] 2> 1876986 T7725 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={isShard=true&q=*:*&ids=17,1,124,6,143,119,127,100,133,137&shards.purpose=64&NOW=1430751029861&wt=javabin&version=2&shard.url=[ff01::083]:33332/aq_vcz/jo|[ff01::213]:33332/aq_vcz/jo|http://127.0.0.1:61427/aq_vcz/jo/collection1&distrib=false} status=0 QTime=2 [junit4] 2> 1876998 T7727 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={sort=n_ti1+desc&q=*:*&shards=[ff01::083]:33332/aq_vcz/jo|[ff01::213]:33332/aq_vcz/jo|http://127.0.0.1:61427/aq_vcz/jo/collection1&wt=javabin&version=2} hits=68 status=0 QTime=29 [junit4] 2> 1877005 T7710 x:collection1 C2008 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={sort=n_ti1+asc&q=*:*&wt=javabin&version=2&distrib=false} hits=68 status=0 QTime=1 [junit4] 2> 1877015 T7726 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={isShard=true&fl=id&sort=n_ti1+asc&q=*:*&shards.purpose=4&NOW=1430751029902&fsv=true&rows=10&wt=javabin&version=2&shard.url=[ff01::213]:33332/aq_vcz/jo|http://127.0.0.1:61427/aq_vcz/jo/collection1|[ff01::083]:33332/aq_vcz/jo&distrib=false&start=0} hits=68 status=0 QTime=0 [junit4] 2> 1877022 T7723 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={isShard=true&q=*:*&ids=148,121,113,107,110,115,142,146,12,111&shards.purpose=64&NOW=1430751029902&wt=javabin&version=2&shard.url=[ff01::213]:33332/aq_vcz/jo|http://127.0.0.1:61427/aq_vcz/jo/collection1|[ff01::083]:33332/aq_vcz/jo&distrib=false} status=0 QTime=1 [junit4] 2> 1877034 T7724 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={sort=n_ti1+asc&q=*:*&shards=[ff01::213]:33332/aq_vcz/jo|http://127.0.0.1:61427/aq_vcz/jo/collection1|[ff01::083]:33332/aq_vcz/jo&wt=javabin&version=2} hits=68 status=0 QTime=24 [junit4] 2> 1877040 T7711 x:collection1 C2008 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={sort=n_f1+desc&q=*:*&wt=javabin&version=2&distrib=false} hits=68 status=0 QTime=1 [junit4] 2> 1877049 T7725 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={isShard=true&fl=id&sort=n_f1+desc&q=*:*&shards.purpose=4&NOW=1430751029937&fsv=true&rows=10&wt=javabin&version=2&shard.url=http://127.0.0.1:61427/aq_vcz/jo/collection1&distrib=false&start=0} hits=68 status=0 QTime=1 [junit4] 2> 1877055 T7727 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={isShard=true&q=*:*&ids=1,9,126,1001,149,117,112,102,133,128&shards.purpose=64&NOW=1430751029937&wt=javabin&version=2&shard.url=http://127.0.0.1:61427/aq_vcz/jo/collection1&distrib=false} status=0 QTime=2 [junit4] 2> 1877064 T7722 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={sort=n_f1+desc&q=*:*&shards=http://127.0.0.1:61427/aq_vcz/jo/collection1&wt=javabin&version=2} hits=68 status=0 QTime=19 [junit4] 2> 1877068 T7712 x:collection1 C2008 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={sort=n_f1+asc&q=*:*&wt=javabin&version=2&distrib=false} hits=68 status=0 QTime=1 [junit4] 2> 1877079 T7723 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={isShard=true&fl=id&sort=n_f1+asc&q=*:*&shards.purpose=4&NOW=1430751029965&fsv=true&rows=10&wt=javabin&version=2&shard.url=[ff01::114]:33332/aq_vcz/jo|http://127.0.0.1:61427/aq_vcz/jo/collection1|[ff01::083]:33332/aq_vcz/jo&distrib=false&start=0} hits=68 status=0 QTime=1 [junit4] 2> 1877086 T7724 x:collection1 C2009 oasc.SolrCore.execute [collection1] webapp=/aq_vcz/jo path=/select params={isShard=true&q=*:*&ids=120,140,110,8,129,136,16,116,139,5&shards.purpose=64&NOW=1430751029965&wt=javabin&version=2&shard.url=[ff01::114]:33332/aq_vcz/jo|http://127.0.0.1:61427/aq_vcz/jo/collection1|[ff01::083]:33332/aq_vcz/ [...truncated too long message...] rvlet.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([96A9A2AE4ED5DA2C:1EFD9D74E029B7D4]: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:1080) [junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1042) [junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:915) [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> 2005648 T7704 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96A9A2AE4ED5DA2C-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {a_n_tdt=BlockTreeOrds(blocksize=128), range_facet_l=PostingsFormat(name=Memory doPackFST= false), multiDefault=PostingsFormat(name=Memory doPackFST= false), n_f1=PostingsFormat(name=LuceneVarGapFixedInterval), n_ti1=Lucene50(blocksize=128), foo_d=Lucene50(blocksize=128), a_t=PostingsFormat(name=Memory doPackFST= false), n_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), rnd_b=Lucene50(blocksize=128), n_tf1=PostingsFormat(name=Memory doPackFST= false), _version_=PostingsFormat(name=LuceneVarGapFixedInterval), a_i1=PostingsFormat(name=Memory doPackFST= false), text=BlockTreeOrds(blocksize=128), n_d1=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false), n_dt1=PostingsFormat(name=LuceneVarGapFixedInterval), oddField_s=Lucene50(blocksize=128), SubjectTerms_mfacet=PostingsFormat(name=LuceneVarGapFixedInterval), lowerfilt1and2=PostingsFormat(name=Memory doPackFST= false), n_l1=PostingsFormat(name=Memory doPackFST= false), n_tdt1=PostingsFormat(name=LuceneVarGapFixedInterval), severity=BlockTreeOrds(blocksize=128), range_facet_l_dv=Lucene50(blocksize=128), foo_sev_enum=BlockTreeOrds(blocksize=128), intDefault=PostingsFormat(name=LuceneVarGapFixedInterval), other_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), b_n_tdt=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= false), foo_f=BlockTreeOrds(blocksize=128), lowerfilt=PostingsFormat(name=Memory doPackFST= false), id=Lucene50(blocksize=128), foo_b=BlockTreeOrds(blocksize=128), n_td1=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{foo_sev_enum=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50), range_facet_l_dv=DocValuesFormat(name=Asserting)}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ja, timezone=Poland [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=3,threads=1,free=164851296,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [HardAutoCommitTest, TestLRUCache, DocExpirationUpdateProcessorFactoryTest, MultiTermTest, SoftAutoCommitTest, TestDocBasedVersionConstraints, TestComponentsName, DirectUpdateHandlerTest, TestSolrIndexConfig, ActionThrottleTest, TestCustomSort, SortByFunctionTest, CursorPagingTest, CloudExitableDirectoryReaderTest, BlockCacheTest, TestAddFieldRealTimeGet, ScriptEngineTest, TestCollapseQParserPlugin, TestSchemaResource, ChangedSchemaMergeTest, TestInfoStreamLogging, QueryElevationComponentTest, TestAnalyzedSuggestions, TestFaceting, TestReqParamsAPI, TestPhraseSuggestions, ClusterStateUpdateTest, ZkControllerTest, TestFieldTypeResource, FieldAnalysisRequestHandlerTest, TestRequestStatusCollectionAPI, HdfsDirectoryTest, JSONWriterTest, TestWordDelimiterFilterFactory, DocValuesTest, SolrCoreTest, TestCSVResponseWriter, OverseerRolesTest, TestLFUCache, TestCSVLoader, TestLeaderElectionZkExpiry, TestRandomDVFaceting, UniqFieldsUpdateProcessorFactoryTest, TestFieldResource, RemoteQueryErrorTest, TestWriterPerf, SharedFSAutoReplicaFailoverUtilsTest, TestSolr4Spatial2, HighlighterMaxOffsetTest, TestInitParams, TestQueryTypes, TestMissingGroups, TestElisionMultitermQuery, SimpleFacetsTest, PrimUtilsTest, TestBinaryField, TestShortCircuitedRequests, TestImplicitCoreProperties, ZkCLITest, PingRequestHandlerTest, SOLR749Test, CoreAdminHandlerTest, TestChildDocTransformer, NoCacheHeaderTest, TestMiniSolrCloudClusterSSL, TestFieldSortValues, SystemInfoHandlerTest, AlternateDirectoryTest, TimeZoneUtilsTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, RequiredFieldsTest, TestSort, TestManagedResource, DOMUtilTest, TestSweetSpotSimilarityFactory, DeleteInactiveReplicaTest, DistributedSuggestComponentTest, PeerSyncTest, CoreMergeIndexesAdminHandlerTest, RankQueryTest, TestFreeTextSuggestions, TestManagedSynonymFilterFactory, TestPartialUpdateDeduplication, MigrateRouteKeyTest, PreAnalyzedFieldTest, BadComponentTest, LeaderElectionTest, FacetPivotSmallTest, CollectionReloadTest, DistributedDebugComponentTest, DistanceUnitsTest, LeaderInitiatedRecoveryOnCommitTest, HdfsUnloadDistributedZkTest, TestJmxMonitoredMap, DistributedFacetPivotSmallAdvancedTest, AnalysisAfterCoreReloadTest, TestOverriddenPrefixQueryForCustomFieldType, ResponseLogComponentTest, DateRangeFieldTest, TestDefaultStatsCache, TestNoOpRegenerator, SolrIndexSplitterTest, DeleteReplicaTest, CircularListTest, TestHashQParserPlugin, SolrXmlInZkTest, TestCollationField, TestBM25SimilarityFactory, HdfsRecoveryZkTest, SolrTestCaseJ4Test, CustomCollectionTest, TestReplicationHandler, TestSearcherReuse, DistribDocExpirationUpdateProcessorTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, RecoveryZkTest, TestZkChroot, ShardRoutingCustomTest, TestDistributedSearch] [junit4] Completed [263/485] on J0 in 131.67s, 1 test, 1 error <<< FAILURES! [...truncated 702 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:536: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:484: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:229: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:511: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1433: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:991: There were test failures: 485 suites, 1950 tests, 1 error, 62 ignored (24 assumptions) Total time: 88 minutes 47 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
