You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/05/25 18:23:46 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 2343
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/2343/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Error from server at http://127.0.0.1:64167//collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:388) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2059) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:640) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:436) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:227) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:196) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) at org.eclipse.jetty.server.Server.handle(Server.java:497) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:64167//collection1: java.lang.NullPointerException
at org.apache.solr.search.grouping.distributed.responseprocessor.TopGroupsShardResponseProcessor.process(TopGroupsShardResponseProcessor.java:102)
at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:744)
at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:727)
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:388)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2059)
at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:640)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:436)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:227)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:196)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
at org.eclipse.jetty.server.Server.handle(Server.java:497)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([96EF5DEB79178C6E:1EBB6231D7EBE196]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10047 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/init-core-data-001
[junit4] 2> 1583512 T7187 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1583513 T7187 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1584059 T7187 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores/collection1
[junit4] 2> 1584065 T7187 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 1584091 T7187 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@4b045b03{/,null,AVAILABLE}
[junit4] 2> 1584092 T7187 oejs.AbstractConnector.doStart Started ServerConnector@737f4099{HTTP/1.1}{127.0.0.1:?????}
[junit4] 2> 1584093 T7187 oejs.Server.doStart Started @???????ms
[junit4] 2> 1584094 T7187 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=64124, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores}
[junit4] 2> 1584096 T7187 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1584096 T7187 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/'
[junit4] 2> 1584145 T7187 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/solr.xml
[junit4] 2> 1584158 T7187 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores
[junit4] 2> 1584159 T7187 oasc.CoreContainer.<init> New CoreContainer 378671367
[junit4] 2> 1584159 T7187 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/]
[junit4] 2> 1584159 T7187 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/lib
[junit4] 2> 1584160 T7187 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/lib).
[junit4] 2> 1584187 T7187 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1584194 T7187 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1584196 T7187 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1584197 T7187 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1584197 T7187 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1584197 T7187 oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used.
[junit4] 2> 1584201 T7187 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores
[junit4] 2> 1584202 T7187 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/}
[junit4] 2> 1584203 T7187 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores/collection1/
[junit4] 2> 1584203 T7187 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1584205 T7199 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1584206 T7199 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1584207 T7199 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1584247 T7199 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1584259 T7199 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1584285 T7199 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1584286 T7199 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 1584293 T7199 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1584450 T7199 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1584462 T7199 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1584463 T7199 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1584473 T7199 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1584476 T7199 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1584478 T7199 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1584480 T7199 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1584481 T7199 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1584482 T7199 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1584483 T7199 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1584483 T7199 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1584483 T7199 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1584483 T7199 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1584484 T7199 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 1584484 T7199 x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@345a8186
[junit4] 2> 1584485 T7199 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores/collection1/data
[junit4] 2> 1584485 T7199 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores/collection1/data/index/
[junit4] 2> 1584486 T7199 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1584486 T7199 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/control/cores/collection1/data/index
[junit4] 2> 1584487 T7199 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1584488 T7199 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@e050a0c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d67c142),segFN=segments_1,generation=1}
[junit4] 2> 1584488 T7199 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1584494 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1584495 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1584495 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1584496 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1584496 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1584496 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1584496 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1584497 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1584498 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1584498 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1584498 T7199 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1584498 T7199 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1584507 T7199 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1584508 T7199 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1584510 T7199 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1584512 T7199 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1584524 T7199 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1584526 T7199 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 1584526 T7199 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1584527 T7199 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1584529 T7199 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1584530 T7199 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1584531 T7199 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=40.2080078125, floorSegmentMB=0.9814453125, forceMergeDeletesPctAllowed=5.529506036058657, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17887914529923946
[junit4] 2> 1584532 T7199 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@e050a0c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d67c142),segFN=segments_1,generation=1}
[junit4] 2> 1584533 T7199 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1584534 T7199 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@13821ba9[collection1] main
[junit4] 2> 1584534 T7199 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1584534 T7199 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1584534 T7199 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1584534 T7199 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1584542 T7199 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1584584 T7199 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1584585 T7199 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1584586 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 1584587 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 1584587 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 1584587 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 1584588 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1584590 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 1584591 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 1584592 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 1584593 T7200 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 1584594 T7200 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13821ba9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1584595 T7187 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1584596 T7187 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1585219 T7187 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1585226 T7187 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 1585231 T7187 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@2137e2b{/,null,AVAILABLE}
[junit4] 2> 1585232 T7187 oejs.AbstractConnector.doStart Started ServerConnector@d682636{HTTP/1.1}{127.0.0.1:?????}
[junit4] 2> 1585233 T7187 oejs.Server.doStart Started @???????ms
[junit4] 2> 1585233 T7187 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=64125, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores}
[junit4] 2> 1585233 T7187 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1585233 T7187 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/'
[junit4] 2> 1585275 T7187 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1585300 T7187 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores
[junit4] 2> 1585301 T7187 oasc.CoreContainer.<init> New CoreContainer 1908893258
[junit4] 2> 1585301 T7187 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/]
[junit4] 2> 1585301 T7187 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/lib
[junit4] 2> 1585302 T7187 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/lib).
[junit4] 2> 1585316 T7187 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1585322 T7187 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1585323 T7187 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1585324 T7187 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1585324 T7187 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1585325 T7187 oasc.CoreContainer.initializeAuthenticationPlugin No authentication plugin used.
[junit4] 2> 1585327 T7187 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores
[junit4] 2> 1585328 T7187 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/}
[junit4] 2> 1585328 T7187 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores/collection1/
[junit4] 2> 1585329 T7187 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1585330 T7213 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1585331 T7213 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1585332 T7213 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1585379 T7213 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1585393 T7213 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1585439 T7213 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1585439 T7213 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 1585445 T7213 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1585596 T7213 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1585607 T7213 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1585608 T7213 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1585617 T7213 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1585619 T7213 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1585622 T7213 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1585622 T7213 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1585622 T7213 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1585623 T7213 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1585623 T7213 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1585623 T7213 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1585624 T7213 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1585624 T7213 x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1585624 T7213 x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/], dataDir=[null]
[junit4] 2> 1585625 T7213 x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@345a8186
[junit4] 2> 1585625 T7213 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores/collection1/data
[junit4] 2> 1585636 T7213 x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores/collection1/data/index/
[junit4] 2> 1585636 T7213 x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1585636 T7213 x:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001/tempDir-001/shard0/cores/collection1/data/index
[junit4] 2> 1585637 T7213 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1585648 T7213 x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12d0983a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ae6e5ab),segFN=segments_1,generation=1}
[junit4] 2> 1585648 T7213 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1585654 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1585655 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1585655 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1585655 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1585656 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1585656 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1585657 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1585661 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1585661 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1585661 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1585662 T7213 x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1585662 T7213 x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1585669 T7213 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1585672 T7213 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1585673 T7213 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1585675 T7213 x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1585682 T7213 x:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1585702 T7213 x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 1585703 T7213 x:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1585705 T7213 x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1585706 T7213 x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1585706 T7213 x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1585717 T7213 x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=40.2080078125, floorSegmentMB=0.9814453125, forceMergeDeletesPctAllowed=5.529506036058657, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17887914529923946
[junit4] 2> 1585717 T7213 x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12d0983a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ae6e5ab),segFN=segments_1,generation=1}
[junit4] 2> 1585718 T7213 x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1585718 T7213 x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@7d9a1d4b[collection1] main
[junit4] 2> 1585719 T7213 x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1585719 T7213 x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1585719 T7213 x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1585720 T7213 x:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1585731 T7213 x:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1585812 T7213 x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1585813 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 1585816 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 1585816 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 1585816 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 1585817 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1585818 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 1585819 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 1585820 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 1585821 T7214 x:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 1585825 T7214 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7d9a1d4b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1585814 T7213 x:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1585826 T7187 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1585827 T7187 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1585829 T7187 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4] 2> ASYNC NEW_CORE C2436 name=collection1 org.apache.solr.core.SolrCore@15206622
[junit4] 2> 1585861 T7191 x:collection1 C2436 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1585862 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> ASYNC NEW_CORE C2437 name=collection1 org.apache.solr.core.SolrCore@5c96955
[junit4] 2> 1585867 T7205 x:collection1 C2437 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1585868 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 1585900 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1]} 0 19
[junit4] 2> 1585920 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1]} 0 11
[junit4] 2> 1585925 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2]} 0 2
[junit4] 2> 1585930 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 1585934 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4] 2> 1585937 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4] 2> 1585943 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4] 2> 1585947 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4] 2> 1585952 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5]} 0 2
[junit4] 2> 1585957 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 1585961 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4] 2> 1585972 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4] 2> 1585977 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7]} 0 1
[junit4] 2> 1585982 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7]} 0 1
[junit4] 2> 1585985 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 1585989 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4] 2> 1585993 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4] 2> 1585997 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4] 2> 1586006 T7194 x:collection1 C2436 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1586019 T7194 x:collection1 C2436 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@e050a0c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d67c142),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@e050a0c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d67c142),segFN=segments_2,generation=2}
[junit4] 2> 1586020 T7194 x:collection1 C2436 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1586022 T7194 x:collection1 C2436 oass.SolrIndexSearcher.<init> Opening Searcher@307621e8[collection1] main
[junit4] 2> 1586023 T7194 x:collection1 C2436 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1586024 T7200 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@307621e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9)))}
[junit4] 2> 1586025 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 19
[junit4] 2> 1586028 T7209 x:collection1 C2437 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1586050 T7209 x:collection1 C2437 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12d0983a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ae6e5ab),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12d0983a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ae6e5ab),segFN=segments_2,generation=2}
[junit4] 2> 1586052 T7209 x:collection1 C2437 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1586055 T7209 x:collection1 C2437 oass.SolrIndexSearcher.<init> Opening Searcher@4e130ca6[collection1] main
[junit4] 2> 1586056 T7209 x:collection1 C2437 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1586057 T7214 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e130ca6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9)))}
[junit4] 2> 1586059 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 31
[junit4] 2> 1586068 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10]} 0 2
[junit4] 2> 1586074 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10]} 0 3
[junit4] 2> 1586082 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11]} 0 1
[junit4] 2> 1586085 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 1586089 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12]} 0 1
[junit4] 2> 1586093 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 1586096 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 1586100 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13]} 0 1
[junit4] 2> 1586108 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001]} 0 2
[junit4] 2> 1586113 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001]} 0 1
[junit4] 2> 1586119 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 1586123 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 1586127 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15]} 0 1
[junit4] 2> 1586130 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 1586136 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 1586139 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 1586144 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17]} 0 1
[junit4] 2> 1586148 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17]} 0 1
[junit4] 2> 1586151 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100]} 0 0
[junit4] 2> 1586155 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100]} 0 1
[junit4] 2> 1586160 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101]} 0 2
[junit4] 2> 1586164 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101]} 0 1
[junit4] 2> 1586168 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102]} 0 0
[junit4] 2> 1586174 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102]} 0 2
[junit4] 2> 1586188 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4] 2> 1586192 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103]} 0 0
[junit4] 2> 1586195 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104]} 0 1
[junit4] 2> 1586199 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104]} 0 1
[junit4] 2> 1586203 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105]} 0 1
[junit4] 2> 1586207 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105]} 0 1
[junit4] 2> 1586211 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106]} 0 1
[junit4] 2> 1586215 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106]} 0 0
[junit4] 2> 1586220 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107]} 0 2
[junit4] 2> 1586224 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107]} 0 1
[junit4] 2> 1586227 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108]} 0 0
[junit4] 2> 1586231 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108]} 0 1
[junit4] 2> 1586235 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109]} 0 1
[junit4] 2> 1586240 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109]} 0 1
[junit4] 2> 1586244 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110]} 0 0
[junit4] 2> 1586248 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110]} 0 1
[junit4] 2> 1586252 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111]} 0 1
[junit4] 2> 1586257 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111]} 0 1
[junit4] 2> 1586260 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4] 2> 1586263 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112]} 0 0
[junit4] 2> 1586268 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113]} 0 1
[junit4] 2> 1586272 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113]} 0 1
[junit4] 2> 1586277 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114]} 0 1
[junit4] 2> 1586280 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114]} 0 0
[junit4] 2> 1586284 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115]} 0 1
[junit4] 2> 1586288 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115]} 0 1
[junit4] 2> 1586292 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116]} 0 1
[junit4] 2> 1586296 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116]} 0 1
[junit4] 2> 1586300 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117]} 0 1
[junit4] 2> 1586304 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117]} 0 2
[junit4] 2> 1586307 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118]} 0 1
[junit4] 2> 1586309 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118]} 0 0
[junit4] 2> 1586312 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4] 2> 1586315 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119]} 0 0
[junit4] 2> 1586320 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120]} 0 2
[junit4] 2> 1586323 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4] 2> 1586326 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121]} 0 0
[junit4] 2> 1586331 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121]} 0 1
[junit4] 2> 1586335 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122]} 0 1
[junit4] 2> 1586340 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122]} 0 1
[junit4] 2> 1586344 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123]} 0 0
[junit4] 2> 1586347 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123]} 0 1
[junit4] 2> 1586353 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124]} 0 1
[junit4] 2> 1586364 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124]} 0 0
[junit4] 2> 1586369 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125]} 0 1
[junit4] 2> 1586372 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125]} 0 0
[junit4] 2> 1586376 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126]} 0 1
[junit4] 2> 1586379 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126]} 0 1
[junit4] 2> 1586383 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127]} 0 0
[junit4] 2> 1586388 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127]} 0 0
[junit4] 2> 1586391 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128]} 0 0
[junit4] 2> 1586399 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128]} 0 3
[junit4] 2> 1586404 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129]} 0 1
[junit4] 2> 1586408 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129]} 0 0
[junit4] 2> 1586411 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4] 2> 1586414 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130]} 0 0
[junit4] 2> 1586417 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131]} 0 1
[junit4] 2> 1586419 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131]} 0 0
[junit4] 2> 1586422 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132]} 0 1
[junit4] 2> 1586424 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132]} 0 0
[junit4] 2> 1586427 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133]} 0 1
[junit4] 2> 1586429 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133]} 0 0
[junit4] 2> 1586432 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134]} 0 1
[junit4] 2> 1586435 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134]} 0 1
[junit4] 2> 1586440 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135]} 0 1
[junit4] 2> 1586443 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135]} 0 0
[junit4] 2> 1586446 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136]} 0 0
[junit4] 2> 1586450 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136]} 0 1
[junit4] 2> 1586453 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137]} 0 1
[junit4] 2> 1586457 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137]} 0 0
[junit4] 2> 1586461 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138]} 0 1
[junit4] 2> 1586464 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138]} 0 1
[junit4] 2> 1586468 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139]} 0 0
[junit4] 2> 1586472 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139]} 0 0
[junit4] 2> 1586476 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140]} 0 1
[junit4] 2> 1586480 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140]} 0 1
[junit4] 2> 1586484 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141]} 0 1
[junit4] 2> 1586488 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141]} 0 1
[junit4] 2> 1586491 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142]} 0 1
[junit4] 2> 1586496 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142]} 0 0
[junit4] 2> 1586501 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143]} 0 1
[junit4] 2> 1586504 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143]} 0 0
[junit4] 2> 1586509 T7194 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4] 2> 1586512 T7209 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144]} 0 0
[junit4] 2> 1586516 T7196 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145]} 0 1
[junit4] 2> 1586520 T7210 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145]} 0 2
[junit4] 2> 1586523 T7191 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4] 2> 1586529 T7205 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146]} 0 0
[junit4] 2> 1586533 T7193 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147]} 0 0
[junit4] 2> 1586538 T7206 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147]} 0 0
[junit4] 2> 1586542 T7192 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148]} 0 1
[junit4] 2> 1586546 T7207 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148]} 0 1
[junit4] 2> 1586552 T7195 x:collection1 C2436 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149]} 0 1
[junit4] 2> 1586556 T7208 x:collection1 C2437 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149]} 0 0
[junit4] 2> 1586559 T7194 x:collection1 C2436 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C2438 name=collection1 org.apache.solr.core.SolrCore@15206622
[junit4] 2> 1586621 T7194 x:collection1 C2438 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@e050a0c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d67c142),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@e050a0c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d67c142),segFN=segments_3,generation=3}
[junit4] 2> 1586622 T7194 x:collection1 C2438 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1586622 T7194 x:collection1 C2438 oass.SolrIndexSearcher.<init> Opening Searcher@37f15d4[collection1] main
[junit4] 2> 1586623 T7194 x:collection1 C2438 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1586624 T7200 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@37f15d4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9) Uninverting(_1(6.0.0):c59)))}
[junit4] 2> 1586625 T7194 x:collection1 C2438 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 66
[junit4] 2> ASYNC NEW_CORE C2439 name=collection1 org.apache.solr.core.SolrCore@5c96955
[junit4] 2> 1586628 T7209 x:collection1 C2439 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1586668 T7209 x:collection1 C2439 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12d0983a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ae6e5ab),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@12d0983a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ae6e5ab),segFN=segments_3,generation=3}
[junit4] 2> 1586674 T7209 x:collection1 C2439 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1586675 T7209 x:collection1 C2439 oass.SolrIndexSearcher.<init> Opening Searcher@20314087[collection1] main
[junit4] 2> 1586677 T7209 x:collection1 C2439 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1586678 T7214 x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@20314087[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9) Uninverting(_1(6.0.0):c59)))}
[junit4] 2> 1586678 T7209 x:collection1 C2439 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 50
[junit4] 2> 1586682 T7196 x:collection1 C2438 oasc.SolrException.log Ignoring exception matching parameter cannot be negative
[junit4] 2> 1586682 T7196 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 1586685 T7191 x:collection1 C2438 oasc.SolrException.log Ignoring exception matching parameter cannot be negative
[junit4] 2> 1586685 T7191 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1586689 T7193 x:collection1 C2438 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> 1586700 T7205 x:collection1 C2439 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=http://127.0.0.1:64125//collection1&rows=10&version=2&q=*:*&NOW=1432569799731&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1586705 T7206 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799731&ids=110,133,144,122,14,147,106,139,118,141&isShard=true&shard.url=http://127.0.0.1:64125//collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1586708 T7210 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:64125//collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=16
[junit4] 2> 1586720 T7192 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1586732 T7208 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:64125//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1432569799767&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1586748 T7209 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799767&ids=143,11,1,102,148,126,116,1001,108,120&isShard=true&shard.url=http://127.0.0.1:64125//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1586752 T7207 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:64125//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=24
[junit4] 2> 1586756 T7195 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1586767 T7206 x:collection1 C2439 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:64125//collection1&rows=10&version=2&q=*:*&NOW=1432569799802&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1586772 T7210 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799802&ids=132,100,111,144,145,123,135,5,106,129&isShard=true&shard.url=http://127.0.0.1:64125//collection1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1586775 T7205 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:64125//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=13
[junit4] 2> 1586805 T7194 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=9
[junit4] 2> 1586815 T7209 x:collection1 C2439 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=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1432569799849&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1586821 T7207 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799849&ids=12,134,101,113,146,116,138,139,9,131&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1586824 T7208 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=14
[junit4] 2> 1586829 T7196 x:collection1 C2438 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> 1586844 T7210 x:collection1 C2439 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:64125//collection1&rows=10&version=2&q=*:*&NOW=1432569799879&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1586851 T7205 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799879&ids=110,132,145,14,103,4,106,141,10,142&isShard=true&shard.url=http://127.0.0.1:64125//collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1586853 T7206 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:64125//collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=13
[junit4] 2> 1586858 T7191 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1586875 T7207 x:collection1 C2439 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:64125//collection1&rows=10&version=2&q=*:*&NOW=1432569799911&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1586880 T7208 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799911&ids=144,2,3,102,137,104,149,117,139,129&isShard=true&shard.url=http://127.0.0.1:64125//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1586883 T7209 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:64125//collection1&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=11
[junit4] 2> 1586886 T7193 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1586893 T7206 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1432569799929&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1586897 T7205 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799929&ids=1,100,2,145,124,137,128,119,10,142&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1586903 T7210 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=13
[junit4] 2> 1586910 T7192 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1586918 T7208 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:64125//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1432569799953&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1586925 T7209 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799953&ids=110,13,113,103,126,116,1001,8,140,120&isShard=true&shard.url=http://127.0.0.1:64125//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1586929 T7207 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:64125//collection1|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=15
[junit4] 2> 1586934 T7195 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1586943 T7205 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:64125//collection1&rows=10&version=2&q=*:*&NOW=1432569799976&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1586946 T7210 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799976&ids=132,121,143,145,3,16,105,129,119,130&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:64125//collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1586947 T7206 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:64125//collection1&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=10
[junit4] 2> 1586952 T7194 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1586959 T7209 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1432569799994&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1586972 T7207 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569799994&ids=12,1,112,15,137,115,116,140,141,120&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1586974 T7208 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:64125//collection1|[ff01::213]:33332/&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=19
[junit4] 2> 1586977 T7196 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1586986 T7210 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:64125//collection1&rows=10&version=2&q=*:*&NOW=1432569800019&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1586991 T7206 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569800019&ids=11,132,133,124,102,126,115,6,106,8&isShard=true&shard.url=http://127.0.0.1:64125//collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1586993 T7205 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:64125//collection1&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=13
[junit4] 2> 1586998 T7191 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1587005 T7207 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:64125//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1432569800041&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1587009 T7208 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569800041&ids=121,12,144,113,146,114,16,129,108,119&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:64125//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1587011 T7209 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:64125//collection1|[ff01::114]:33332/&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=9
[junit4] 2> 1587015 T7193 x:collection1 C2438 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1587021 T7206 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:64125//collection1|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1432569800057&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1587025 T7205 x:collection1 C2439 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1432569800057&ids=121,144,145,135,15,5,104,118,130,120&isShard=true&shard.url=http://127.0.0.1:64125//collect
[...truncated too long message...]
rCall.java:640)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:436)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:227)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:196)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:105)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
[junit4] > at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:497)
[junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
[junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
[junit4] > at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([96EF5DEB79178C6E:1EBB6231D7EBE196]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
[junit4] > at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1133)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1095)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:968)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:982)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1718738 T7187 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 96EF5DEB79178C6E-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=PostingsFormat(name=Asserting), range_facet_l_dv=PostingsFormat(name=Direct), SubjectTerms_mfacet=PostingsFormat(name=Asserting), n_l1=PostingsFormat(name=Asserting), a_n_tdt=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=Asserting), n_td1=PostingsFormat(name=Memory doPackFST= false), n_d1=PostingsFormat(name=Asserting), n_f1=PostingsFormat(name=Memory doPackFST= false), range_facet_l=PostingsFormat(name=Asserting), n_tl1=PostingsFormat(name=Memory doPackFST= false), oddField_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), n_tf1=PostingsFormat(name=Asserting), lowerfilt1and2=PostingsFormat(name=Memory doPackFST= false), a_t=PostingsFormat(name=Asserting), lowerfilt=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=Direct), text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), timestamp=PostingsFormat(name=Asserting), severity=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), foo_sev_enum=PostingsFormat(name=Direct), n_dt1=PostingsFormat(name=Memory doPackFST= false), a_i1=PostingsFormat(name=Asserting), b_n_tdt=PostingsFormat(name=Asserting), n_ti1=PostingsFormat(name=Direct), rnd_b=PostingsFormat(name=Direct), foo_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), n_tdt1=PostingsFormat(name=Asserting), foo_d=PostingsFormat(name=Direct), range_facet_i_dv=PostingsFormat(name=Asserting), foo_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), foo_sev_enum=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=hi_IN, timezone=Pacific/Port_Moresby
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=3,threads=1,free=115431672,total=488812544
[junit4] 2> NOTE: All tests run in this JVM: [StressHdfsTest, ConcurrentDeleteAndCreateCollectionTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, TestStressReorder, DistribDocExpirationUpdateProcessorTest, DeleteReplicaTest, TestReplicationHandler, DocValuesMultiTest, BadComponentTest, TestCloudManagedSchema, TestWriterPerf, TestUpdate, TestReRankQParserPlugin, TestImplicitCoreProperties, HdfsRecoveryZkTest, TestShardHandlerFactory, DistributedFacetPivotWhiteBoxTest, DirectUpdateHandlerTest, DistributedIntervalFacetingTest, QueryElevationComponentTest, UUIDFieldTest, SSLMigrationTest, TestSurroundQueryParser, TestBinaryField, TestRemoveLastDynamicCopyField, TestChildDocTransformer, FieldAnalysisRequestHandlerTest, SolrTestCaseJ4Test, CdcrUpdateLogTest, DistribCursorPagingTest, CursorMarkTest, CollectionsAPIAsyncDistributedZkTest, SOLR749Test, TestNRTOpen, ZkCLITest, CoreAdminHandlerTest, MultiThreadedOCPTest, AlternateDirectoryTest, TestMiniSolrCloudClusterSSL, TestFaceting, SharedFSAutoReplicaFailoverTest, TestConfigSets, OutOfBoxZkACLAndCredentialsProvidersTest, ClusterStateUpdateTest, XmlUpdateRequestHandlerTest, UnloadDistributedZkTest, UniqFieldsUpdateProcessorFactoryTest, TestStressRecovery, TestWordDelimiterFilterFactory, SharedFSAutoReplicaFailoverUtilsTest, TestSchemaResource, TestSchemaNameResource, DistributedSpellCheckComponentTest, OverseerTest, TestExceedMaxTermLength, TestArbitraryIndexDir, TestPostingsSolrHighlighter, TestDocBasedVersionConstraints, TestDynamicLoading, TestDistributedStatsComponentCardinality, FacetPivotSmallTest, TestSweetSpotSimilarityFactory, RulesTest, TestCSVLoader, TestReplicationHandlerBackup, JSONWriterTest, TestHighlightDedupGrouping, DocValuesTest, TestInitQParser, URLClassifyProcessorTest, TestIndexingPerformance, TestCollationFieldDocValues, TestCollationField, RemoteQueryErrorTest, TestLeaderElectionZkExpiry, LeaderFailoverAfterPartitionTest, InfoHandlerTest, TestHashQParserPlugin, TestLFUCache, TestRawResponseWriter, TestStressVersions, TestFieldResource, TestFieldTypeCollectionResource, IndexSchemaRuntimeFieldTest, TestAuthorizationFramework, TestOmitPositions, PingRequestHandlerTest, TestGroupingSearch, TestFastLRUCache, SampleTest, CollectionReloadTest, TestDefaultStatsCache, TestConfigOverlay, TestAuthenticationFramework, PathHierarchyTokenizerFactoryTest, TestMaxScoreQueryParser, TestAnalyzedSuggestions, TestManagedSchemaDynamicFieldResource, TestHashPartitioner, TestDistributedSearch]
[junit4] Completed [232/502] on J0 in 135.26s, 1 test, 1 error <<< FAILURES!
[...truncated 826 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:526: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:474: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:229: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:512: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1415: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:973: There were test failures: 502 suites, 1984 tests, 1 error, 62 ignored (24 assumptions)
Total time: 71 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any