Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/12162/ Java: 64bit/jdk1.8.0_60-ea-b06 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.TestDistributedSearch.test Error Message: Error from server at http://127.0.0.1:42180//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:355) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:1984) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:829) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:103) 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:42180//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:355) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:1984) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:829) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:103) 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([A827F393A1F03F0:82D640E394E36E08]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:556) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:233) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:225) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958) at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1055) at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1017) at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:890) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 10068 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/init-core-data-001 [junit4] 2> 713216 T4160 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 713216 T4160 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 713274 T4160 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1 [junit4] 2> 713279 T4160 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 713280 T4160 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@5417c6fc{/,null,AVAILABLE} [junit4] 2> 713281 T4160 oejs.AbstractConnector.doStart Started ServerConnector@ca77618{HTTP/1.1}{127.0.0.1:44684} [junit4] 2> 713281 T4160 oejs.Server.doStart Started @714317ms [junit4] 2> 713281 T4160 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=44684, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores} [junit4] 2> 713282 T4160 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 713282 T4160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/' [junit4] 2> 713291 T4160 oasc.SolrXmlConfig.fromFile Loading container configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/solr.xml [junit4] 2> 713294 T4160 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores [junit4] 2> 713294 T4160 oasc.CoreContainer.<init> New CoreContainer 1826548433 [junit4] 2> 713294 T4160 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/] [junit4] 2> 713294 T4160 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/lib [junit4] 2> 713295 T4160 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/lib). [junit4] 2> 713302 T4160 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 713302 T4160 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 713302 T4160 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 713303 T4160 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 713303 T4160 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 713303 T4160 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 713303 T4160 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 713303 T4160 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 713303 T4160 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 713303 T4160 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 713304 T4160 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 713304 T4160 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 713304 T4160 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 713304 T4160 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 713304 T4160 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 713305 T4160 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores [junit4] 2> 713305 T4160 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 713305 T4160 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1/ [junit4] 2> 713306 T4160 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 713306 T4172 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 713306 T4172 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 713306 T4172 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 713316 T4172 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 713323 T4172 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 713347 T4172 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 713348 T4172 oass.IndexSchema.readSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 713350 T4172 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 713413 T4172 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 713417 T4172 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 713417 T4172 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 713420 T4172 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 713421 T4172 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 713421 T4172 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 713422 T4172 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 713422 T4172 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 713422 T4172 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 713422 T4172 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 713422 T4172 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 713422 T4172 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1 [junit4] 2> 713422 T4172 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 713423 T4172 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1/data/ [junit4] 2> 713423 T4172 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4df629a2 [junit4] 2> 713423 T4172 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1/data [junit4] 2> 713423 T4172 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1/data/index/ [junit4] 2> 713423 T4172 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 713424 T4172 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/control/cores/collection1/data/index [junit4] 2> 713424 T4172 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=88.849609375, floorSegmentMB=0.255859375, forceMergeDeletesPctAllowed=0.3691072133022921, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 713424 T4172 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14a2ec94 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38d764c9),segFN=segments_1,generation=1} [junit4] 2> 713424 T4172 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 713426 T4172 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 713427 T4172 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 713427 T4172 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 713427 T4172 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 713427 T4172 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 713427 T4172 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 713427 T4172 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 713427 T4172 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 713428 T4172 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 713428 T4172 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 713428 T4172 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 713428 T4172 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 713429 T4172 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 713429 T4172 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 713430 T4172 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 713430 T4172 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 713432 T4172 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 713433 T4172 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 713433 T4172 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 713434 T4172 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 713434 T4172 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 713434 T4172 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 713435 T4172 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 713435 T4172 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 713435 T4172 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@74fb1ea2 [junit4] 2> 713435 T4172 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14a2ec94 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38d764c9),segFN=segments_1,generation=1} [junit4] 2> 713435 T4172 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 713435 T4172 oass.SolrIndexSearcher.<init> Opening Searcher@3c60ee5f[collection1] main [junit4] 2> 713436 T4172 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 713436 T4172 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 713436 T4172 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 713436 T4172 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 713490 T4172 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 713584 T4172 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 713584 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 713585 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 713585 T4172 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 713585 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 713585 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 713585 T4160 oass.SolrDispatchFilter.init user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 [junit4] 2> 713585 T4160 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 713585 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 713586 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 713586 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 713586 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 713586 T4173 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 713587 T4173 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c60ee5f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 713627 T4160 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 713628 T4160 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 713629 T4160 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@55fc01c5{/,null,AVAILABLE} [junit4] 2> 713630 T4160 oejs.AbstractConnector.doStart Started ServerConnector@7ef74f29{HTTP/1.1}{127.0.0.1:51362} [junit4] 2> 713630 T4160 oejs.Server.doStart Started @714666ms [junit4] 2> 713630 T4160 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=51362, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores} [junit4] 2> 713630 T4160 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 713630 T4160 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/' [junit4] 2> 713639 T4160 oasc.SolrXmlConfig.fromFile Loading container configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/solr.xml [junit4] 2> 713642 T4160 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores [junit4] 2> 713642 T4160 oasc.CoreContainer.<init> New CoreContainer 1373853298 [junit4] 2> 713643 T4160 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/] [junit4] 2> 713643 T4160 oasc.CoreContainer.load loading shared library: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/lib [junit4] 2> 713643 T4160 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/lib). [junit4] 2> 713647 T4160 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 713647 T4160 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 713647 T4160 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 713647 T4160 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 713647 T4160 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 713647 T4160 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 713648 T4160 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 713648 T4160 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 713648 T4160 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 713648 T4160 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 713648 T4160 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 713648 T4160 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 713649 T4160 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 713649 T4160 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 713649 T4160 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 713649 T4160 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores [junit4] 2> 713650 T4160 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/} [junit4] 2> 713650 T4160 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1/ [junit4] 2> 713650 T4160 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 713650 T4186 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/' [junit4] 2> 713651 T4186 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 713651 T4186 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 713662 T4186 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 713680 T4186 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 713694 T4186 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 713695 T4186 oass.IndexSchema.readSchema Reading Solr Schema from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 713698 T4186 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 713765 T4186 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 713768 T4186 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 713769 T4186 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 713771 T4186 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 713773 T4186 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 713773 T4186 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 713774 T4186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 713774 T4186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 713774 T4186 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 713774 T4186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 713774 T4186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 713774 T4186 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1 [junit4] 2> 713774 T4186 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 713775 T4186 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/, dataDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1/data/ [junit4] 2> 713775 T4186 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4df629a2 [junit4] 2> 713775 T4186 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1/data [junit4] 2> 713775 T4186 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1/data/index/ [junit4] 2> 713775 T4186 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 713776 T4186 oasc.CachingDirectoryFactory.get return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001/tempDir-001/shard0/cores/collection1/data/index [junit4] 2> 713776 T4186 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=88.849609375, floorSegmentMB=0.255859375, forceMergeDeletesPctAllowed=0.3691072133022921, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 713776 T4186 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@772fb52d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30364c09),segFN=segments_1,generation=1} [junit4] 2> 713776 T4186 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 713779 T4186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 713779 T4186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 713779 T4186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 713779 T4186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 713779 T4186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 713779 T4186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 713779 T4186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 713780 T4186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 713780 T4186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 713780 T4186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 713780 T4186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 713780 T4186 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 713781 T4186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 713782 T4186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 713782 T4186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 713783 T4186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 713786 T4186 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 713787 T4186 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump [junit4] 2> 713788 T4186 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 713789 T4186 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 713789 T4186 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 713789 T4186 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 713789 T4186 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 713790 T4186 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 713790 T4186 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@5e0e3024 [junit4] 2> 713790 T4186 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@772fb52d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30364c09),segFN=segments_1,generation=1} [junit4] 2> 713790 T4186 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 713790 T4186 oass.SolrIndexSearcher.<init> Opening Searcher@ec3f664[collection1] main [junit4] 2> 713790 T4186 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 713791 T4186 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 713791 T4186 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 713791 T4186 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 713822 T4186 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 713901 T4186 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 713902 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 713902 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 713902 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 713902 T4186 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 713902 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 713902 T4160 oass.SolrDispatchFilter.init user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 [junit4] 2> 713903 T4160 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 713902 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 713903 T4160 oas.SolrTestCaseJ4.setUp ###Starting test [junit4] 2> 713903 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 713903 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 713903 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 713904 T4187 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 713905 T4187 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ec3f664[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> ASYNC NEW_CORE C1014 name=collection1 org.apache.solr.core.SolrCore@691077ba [junit4] 2> 713907 T4167 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1497120321417773056)} 0 2 [junit4] 2> ASYNC NEW_CORE C1015 name=collection1 org.apache.solr.core.SolrCore@2eb9b617 [junit4] 2> 713909 T4181 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1497120321419870208)} 0 1 [junit4] 2> 713913 T4168 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1497120321423015936)]} 0 2 [junit4] 2> 713916 T4182 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1497120321427210240)]} 0 1 [junit4] 2> 713918 T4169 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1497120321429307392)]} 0 1 [junit4] 2> 713920 T4183 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1497120321432453120)]} 0 0 [junit4] 2> 713923 T4167 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1497120321434550272)]} 0 1 [junit4] 2> 713925 T4181 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1497120321436647424)]} 0 1 [junit4] 2> 713927 T4168 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1497120321438744576)]} 0 1 [junit4] 2> 713929 T4182 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1497120321441890304)]} 0 1 [junit4] 2> 713931 T4169 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1497120321443987456)]} 0 1 [junit4] 2> 713933 T4183 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1497120321446084608)]} 0 1 [junit4] 2> 713935 T4167 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1497120321448181760)]} 0 1 [junit4] 2> 713937 T4181 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1497120321449230336)]} 0 1 [junit4] 2> 713938 T4168 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1497120321451327488)]} 0 0 [junit4] 2> 713940 T4182 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1497120321453424640)]} 0 0 [junit4] 2> 713942 T4169 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1497120321455521792)]} 0 0 [junit4] 2> 713944 T4183 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1497120321457618944)]} 0 0 [junit4] 2> 713946 T4167 c:collection1 C1014 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1497120321459716096)]} 0 0 [junit4] 2> 713948 T4181 c:collection1 C1015 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1497120321461813248)]} 0 0 [junit4] 2> 713950 T4168 c:collection1 C1014 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> ASYNC NEW_CORE C1016 name=collection1 org.apache.solr.core.SolrCore@691077ba [junit4] 2> 713971 T4168 c:collection1 C1016 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14a2ec94 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38d764c9),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14a2ec94 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38d764c9),segFN=segments_2,generation=2} [junit4] 2> 713972 T4168 c:collection1 C1016 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 713974 T4168 c:collection1 C1016 oass.SolrIndexSearcher.<init> Opening Searcher@6fad3b52[collection1] main [junit4] 2> 713974 T4168 c:collection1 C1016 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 713975 T4173 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6fad3b52[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)))} [junit4] 2> 713975 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 25 [junit4] 2> ASYNC NEW_CORE C1017 name=collection1 org.apache.solr.core.SolrCore@2eb9b617 [junit4] 2> 713977 T4182 c:collection1 C1017 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 713981 T4182 c:collection1 C1017 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@772fb52d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30364c09),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@772fb52d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30364c09),segFN=segments_2,generation=2} [junit4] 2> 713982 T4182 c:collection1 C1017 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 713983 T4182 c:collection1 C1017 oass.SolrIndexSearcher.<init> Opening Searcher@50a27c72[collection1] main [junit4] 2> 713983 T4182 c:collection1 C1017 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 713984 T4187 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50a27c72[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C9)))} [junit4] 2> 713984 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 8 [junit4] 2> 713988 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1497120321501659136)]} 0 2 [junit4] 2> 713990 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1497120321504804864)]} 0 1 [junit4] 2> 713992 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1497120321507950592)]} 0 0 [junit4] 2> 713995 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1497120321510047744)]} 0 1 [junit4] 2> 713996 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1497120321512144896)]} 0 0 [junit4] 2> 713998 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1497120321514242048)]} 0 0 [junit4] 2> 714000 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1497120321516339200)]} 0 0 [junit4] 2> 714002 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1497120321518436352)]} 0 1 [junit4] 2> 714004 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1497120321519484928)]} 0 1 [junit4] 2> 714006 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1497120321521582080)]} 0 1 [junit4] 2> 714008 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1497120321523679232)]} 0 1 [junit4] 2> 714009 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1497120321525776384)]} 0 0 [junit4] 2> 714011 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1497120321527873536)]} 0 0 [junit4] 2> 714013 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1497120321529970688)]} 0 0 [junit4] 2> 714015 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1497120321531019264)]} 0 1 [junit4] 2> 714017 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1497120321533116416)]} 0 1 [junit4] 2> 714019 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1497120321535213568)]} 0 1 [junit4] 2> 714021 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1497120321537310720)]} 0 1 [junit4] 2> 714022 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1497120321539407872)]} 0 0 [junit4] 2> 714024 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1497120321541505024)]} 0 0 [junit4] 2> 714026 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1497120321543602176)]} 0 1 [junit4] 2> 714027 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1497120321544650752)]} 0 0 [junit4] 2> 714029 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1497120321546747904)]} 0 0 [junit4] 2> 714031 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1497120321548845056)]} 0 0 [junit4] 2> 714033 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1497120321550942208)]} 0 0 [junit4] 2> 714035 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1497120321551990784)]} 0 1 [junit4] 2> 714036 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1497120321554087936)]} 0 0 [junit4] 2> 714038 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1497120321556185088)]} 0 0 [junit4] 2> 714040 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1497120321558282240)]} 0 0 [junit4] 2> 714042 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1497120321560379392)]} 0 0 [junit4] 2> 714044 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1497120321562476544)]} 0 0 [junit4] 2> 714046 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1497120321564573696)]} 0 0 [junit4] 2> 714048 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1497120321566670848)]} 0 1 [junit4] 2> 714050 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1497120321568768000)]} 0 0 [junit4] 2> 714052 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1497120321570865152)]} 0 0 [junit4] 2> 714054 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1497120321571913728)]} 0 1 [junit4] 2> 714056 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1497120321574010880)]} 0 1 [junit4] 2> 714057 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1497120321576108032)]} 0 0 [junit4] 2> 714059 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1497120321578205184)]} 0 0 [junit4] 2> 714061 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1497120321580302336)]} 0 0 [junit4] 2> 714063 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1497120321582399488)]} 0 0 [junit4] 2> 714066 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1497120321585545216)]} 0 0 [junit4] 2> 714068 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1497120321587642368)]} 0 0 [junit4] 2> 714070 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1497120321589739520)]} 0 1 [junit4] 2> 714072 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1497120321590788096)]} 0 1 [junit4] 2> 714073 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1497120321592885248)]} 0 0 [junit4] 2> 714075 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1497120321594982400)]} 0 0 [junit4] 2> 714077 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1497120321597079552)]} 0 0 [junit4] 2> 714079 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1497120321598128128)]} 0 1 [junit4] 2> 714080 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1497120321600225280)]} 0 0 [junit4] 2> 714082 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1497120321602322432)]} 0 0 [junit4] 2> 714084 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1497120321604419584)]} 0 0 [junit4] 2> 714086 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1497120321606516736)]} 0 1 [junit4] 2> 714087 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1497120321607565312)]} 0 0 [junit4] 2> 714089 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1497120321609662464)]} 0 0 [junit4] 2> 714091 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1497120321611759616)]} 0 0 [junit4] 2> 714093 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1497120321613856768)]} 0 0 [junit4] 2> 714095 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1497120321614905344)]} 0 1 [junit4] 2> 714096 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1497120321617002496)]} 0 0 [junit4] 2> 714098 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1497120321619099648)]} 0 0 [junit4] 2> 714100 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1497120321621196800)]} 0 0 [junit4] 2> 714102 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1497120321623293952)]} 0 0 [junit4] 2> 714104 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1497120321625391104)]} 0 0 [junit4] 2> 714106 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1497120321626439680)]} 0 1 [junit4] 2> 714108 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1497120321628536832)]} 0 1 [junit4] 2> 714110 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1497120321630633984)]} 0 1 [junit4] 2> 714111 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1497120321632731136)]} 0 0 [junit4] 2> 714113 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1497120321634828288)]} 0 0 [junit4] 2> 714115 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1497120321636925440)]} 0 0 [junit4] 2> 714117 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1497120321639022592)]} 0 0 [junit4] 2> 714119 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1497120321640071168)]} 0 1 [junit4] 2> 714120 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1497120321642168320)]} 0 0 [junit4] 2> 714122 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1497120321644265472)]} 0 0 [junit4] 2> 714124 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1497120321646362624)]} 0 0 [junit4] 2> 714125 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1497120321647411200)]} 0 0 [junit4] 2> 714127 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1497120321649508352)]} 0 0 [junit4] 2> 714129 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1497120321651605504)]} 0 0 [junit4] 2> 714131 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1497120321653702656)]} 0 1 [junit4] 2> 714132 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1497120321654751232)]} 0 0 [junit4] 2> 714134 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1497120321656848384)]} 0 0 [junit4] 2> 714136 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1497120321658945536)]} 0 0 [junit4] 2> 714138 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1497120321659994112)]} 0 1 [junit4] 2> 714139 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1497120321662091264)]} 0 0 [junit4] 2> 714141 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1497120321664188416)]} 0 0 [junit4] 2> 714143 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1497120321665236992)]} 0 1 [junit4] 2> 714144 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1497120321667334144)]} 0 0 [junit4] 2> 714146 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1497120321669431296)]} 0 0 [junit4] 2> 714148 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1497120321670479872)]} 0 1 [junit4] 2> 714149 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1497120321672577024)]} 0 0 [junit4] 2> 714151 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1497120321674674176)]} 0 0 [junit4] 2> 714153 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1497120321676771328)]} 0 0 [junit4] 2> 714154 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1497120321677819904)]} 0 0 [junit4] 2> 714156 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1497120321679917056)]} 0 0 [junit4] 2> 714158 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1497120321682014208)]} 0 0 [junit4] 2> 714160 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1497120321683062784)]} 0 1 [junit4] 2> 714161 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1497120321685159936)]} 0 0 [junit4] 2> 714163 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1497120321687257088)]} 0 0 [junit4] 2> 714165 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1497120321689354240)]} 0 0 [junit4] 2> 714167 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1497120321691451392)]} 0 1 [junit4] 2> 714168 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1497120321692499968)]} 0 0 [junit4] 2> 714170 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1497120321694597120)]} 0 0 [junit4] 2> 714172 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1497120321696694272)]} 0 1 [junit4] 2> 714173 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1497120321697742848)]} 0 0 [junit4] 2> 714175 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1497120321699840000)]} 0 0 [junit4] 2> 714177 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1497120321701937152)]} 0 0 [junit4] 2> 714179 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1497120321702985728)]} 0 1 [junit4] 2> 714180 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1497120321705082880)]} 0 0 [junit4] 2> 714182 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1497120321707180032)]} 0 0 [junit4] 2> 714184 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1497120321709277184)]} 0 0 [junit4] 2> 714186 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1497120321710325760)]} 0 1 [junit4] 2> 714187 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1497120321712422912)]} 0 0 [junit4] 2> 714189 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1497120321714520064)]} 0 0 [junit4] 2> 714191 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1497120321716617216)]} 0 0 [junit4] 2> 714193 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1497120321718714368)]} 0 0 [junit4] 2> 714195 T4169 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1497120321720811520)]} 0 0 [junit4] 2> 714197 T4183 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1497120321722908672)]} 0 0 [junit4] 2> 714199 T4167 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1497120321725005824)]} 0 0 [junit4] 2> 714201 T4181 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1497120321727102976)]} 0 0 [junit4] 2> 714203 T4168 c:collection1 C1016 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 714224 T4168 c:collection1 C1016 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14a2ec94 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38d764c9),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14a2ec94 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@38d764c9),segFN=segments_3,generation=3} [junit4] 2> 714225 T4168 c:collection1 C1016 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 714226 T4168 c:collection1 C1016 oass.SolrIndexSearcher.<init> Opening Searcher@1bdc5172[collection1] main [junit4] 2> 714226 T4168 c:collection1 C1016 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 714227 T4173 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bdc5172[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.0.0):c9) Uninverting(_1(6.0.0):C59)))} [junit4] 2> 714227 T4168 c:collection1 C1016 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 24 [junit4] 2> 714229 T4182 c:collection1 C1017 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 714248 T4182 c:collection1 C1017 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@772fb52d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30364c09),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@772fb52d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30364c09),segFN=segments_3,generation=3} [junit4] 2> 714248 T4182 c:collection1 C1017 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 714249 T4182 c:collection1 C1017 oass.SolrIndexSearcher.<init> Opening Searcher@1a201964[collection1] main [junit4] 2> 714249 T4182 c:collection1 C1017 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 714250 T4187 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a201964[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.0.0):c9) Uninverting(_1(6.0.0):C59)))} [junit4] 2> 714250 T4182 c:collection1 C1017 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 21 [junit4] 2> 714253 T4169 c:collection1 C1016 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:196) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1984) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:829) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:103) [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> 714254 T4169 c:collection1 C1016 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=2 [junit4] 2> 714256 T4167 c:collection1 C1016 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:196) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1984) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:829) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:103) [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> 714257 T4167 c:collection1 C1016 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=1 [junit4] 2> 714259 T4168 c:collection1 C1016 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 714266 T4181 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:51362//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1427765199462&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 714269 T4182 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1427765199462&ids=111,100,13,2,3,125,5,116,127,108&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:51362//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 714270 T4183 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:51362//collection1|[ff01::083]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8 [junit4] 2> 714272 T4169 c:collection1 C1016 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 714277 T4182 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:51362//collection1&rows=10&version=2&q=*:*&NOW=1427765199474&isShard=true&wt=javabin} hits=68 status=0 QTime=1 [junit4] 2> 714279 T4183 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1427765199474&ids=11,110,144,133,112,6,105,107,118,119&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:51362//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 714280 T4181 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:51362//collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 [junit4] 2> 714282 T4167 c:collection1 C1016 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 714286 T4183 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:51362//collection1&rows=10&version=2&q=*:*&NOW=1427765199484&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 714288 T4181 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1427765199484&ids=11,121,132,123,134,102,137,115,118,108&isShard=true&shard.url=http://127.0.0.1:51362//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 714289 T4182 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:51362//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 714291 T4168 c:collection1 C1016 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 714295 T4181 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:51362//collection1&rows=10&version=2&q=*:*&NOW=1427765199493&isShard=true&wt=javabin} hits=68 status=0 QTime=1 [junit4] 2> 714297 T4182 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1427765199493&ids=13,15,4,104,5,127,6,105,1001,129&isShard=true&shard.url=http://127.0.0.1:51362//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 714297 T4183 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:51362//collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 714300 T4169 c:collection1 C1016 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 714303 T4182 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:51362//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1427765199502&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 714306 T4183 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1427765199502&ids=132,144,2,145,14,15,148,8,141,10&isShard=true&shard.url=http://127.0.0.1:51362//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 714306 T4181 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:51362//collection1|[ff01::213]:33332/&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 714309 T4167 c:collection1 C1016 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 714312 T4183 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:51362//collection1&rows=10&version=2&q=*:*&NOW=1427765199511&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 714315 T4181 c:collection1 C1017 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1427765199511&ids=110,101,147,125,16,106,107,108,9,131&isShard=true&shard.url=http://127.0.0.1:51362//collection1&wt=javabin&version=2} status=0 QTime=1 [...truncated too long message...] 19bf{/,null,UNAVAILABLE} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=A827F393A1F03F0 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_MX -Dtests.timezone=Asia/Tokyo -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 82.2s J1 | TestDistributedSearch.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:42180//collection1: java.lang.NullPointerException [junit4] > at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102) [junit4] > at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744) [junit4] > at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727) [junit4] > at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:355) [junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) [junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:1984) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:829) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:446) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:103) [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([A827F393A1F03F0:82D640E394E36E08]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:556) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:233) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:225) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958) [junit4] > at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1055) [junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1017) [junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:890) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 795442 T4160 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch A827F393A1F03F0-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=FST50, SubjectTerms_mfacet=FST50, n_l1=FST50, a_n_tdt=PostingsFormat(name=LuceneFixedGap), multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), intDefault=FST50, n_td1=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_d1=FST50, n_f1=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_l=FST50, n_tl1=PostingsFormat(name=LuceneVarGapDocFreqInterval), oddField_s=FSTOrd50, n_tf1=FST50, lowerfilt1and2=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_t=FST50, lowerfilt=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=PostingsFormat(name=LuceneFixedGap), text=FSTOrd50, timestamp=FST50, severity=FSTOrd50, foo_sev_enum=PostingsFormat(name=LuceneFixedGap), n_dt1=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_i1=FST50, b_n_tdt=FST50, n_ti1=PostingsFormat(name=LuceneFixedGap), rnd_b=PostingsFormat(name=LuceneFixedGap), _version_=FST50, foo_b=FSTOrd50, n_tdt1=FST50, foo_d=PostingsFormat(name=LuceneFixedGap), foo_f=FSTOrd50}, docValues:{foo_sev_enum=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Direct)}, sim=DefaultSimilarity, locale=es_MX, timezone=Asia/Tokyo [junit4] 2> NOTE: Linux 3.13.0-46-generic amd64/Oracle Corporation 1.8.0_60-ea (64-bit)/cpus=12,threads=2,free=193502952,total=509607936 [junit4] 2> NOTE: All tests run in this JVM: [SSLMigrationTest, TestFuzzyAnalyzedSuggestions, TestLFUCache, TestSolrQueryParserDefaultOperatorResource, ShardRoutingCustomTest, RemoteQueryErrorTest, TestValueSourceCache, UpdateParamsTest, HdfsBasicDistributedZkTest, TestStandardQParsers, TestRestManager, TestAnalyzeInfixSuggestions, TestWriterPerf, TestBlendedInfixSuggestions, DirectSolrConnectionTest, TestCoreDiscovery, HdfsCollectionsAPIDistributedZkTest, HttpPartitionTest, AlternateDirectoryTest, TestElisionMultitermQuery, SpellCheckComponentTest, SolrXmlInZkTest, HdfsThreadLeakTest, ExternalCollectionsTest, FieldMutatingUpdateProcessorTest, SolrCmdDistributorTest, TestNamedUpdateProcessors, TestDistributedMissingSort, ChaosMonkeySafeLeaderTest, TestSchemaManager, CacheHeaderTest, HdfsSyncSliceTest, TestHighlightDedupGrouping, ShowFileRequestHandlerTest, TestCoreContainer, JsonLoaderTest, PreAnalyzedFieldTest, TestOmitPositions, TestLMDirichletSimilarityFactory, TestIndexSearcher, TestManagedStopFilterFactory, ClusterStateUpdateTest, TestSerializedLuceneMatchVersion, TestCustomSort, CoreAdminHandlerTest, TestManagedResourceStorage, ResourceLoaderTest, BasicFunctionalityTest, SliceStateTest, TestComponentsName, SpellingQueryConverterTest, TestSuggestSpellingConverter, TestCryptoKeys, DistanceFunctionTest, TestIntervalFaceting, HdfsUnloadDistributedZkTest, DistribCursorPagingTest, SpatialFilterTest, DisMaxRequestHandlerTest, ReturnFieldsTest, DeleteShardTest, TestStressVersions, CopyFieldTest, CollectionsAPIAsyncDistributedZkTest, QueryResultKeyTest, TestAtomicUpdateErrorCases, TestFastWriter, TestBlobHandler, TestFiltering, TestComplexPhraseQParserPlugin, TestPHPSerializedResponseWriter, SolrPluginUtilsTest, TestLRUCache, TestConfigOverlay, TestCharFilters, SpellPossibilityIteratorTest, TestEmbeddedSolrServerConstructors, DocumentAnalysisRequestHandlerTest, CollectionsAPIDistributedZkTest, ShardRoutingTest, ZkCLITest, TestDistributedSearch] [junit4] Completed on J1 in 82.24s, 1 test, 1 error <<< FAILURES! [...truncated 533 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:191: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1351: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:958: There were test failures: 479 suites, 1901 tests, 1 error, 49 ignored (21 assumptions) Total time: 37 minutes 23 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0_60-ea-b06 -XX:-UseCompressedOops -XX:+UseSerialGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org