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/04/19 00:19:01 UTC
[JENKINS] Lucene-Solr-5.x-MacOSX (64bit/jdk1.8.0) - Build # 2148 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/2148/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Error from server at http://127.0.0.1:49482//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:1988) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235) at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82) at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628) at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) 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:49482//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:1988)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([60DB72B4CDACE177:E88F4D6E63508C8F]: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:1067)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1029)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:902)
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)
FAILED: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test
Error Message:
There were too many update fails (28 > 20) - we expect it can happen, but shouldn't easily
Stack Trace:
java.lang.AssertionError: There were too many update fails (28 > 20) - we expect it can happen, but shouldn't easily
at __randomizedtesting.SeedInfo.seed([60DB72B4CDACE177:E88F4D6E63508C8F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertFalse(Assert.java:68)
at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test(ChaosMonkeyNothingIsSafeTest.java:230)
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$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
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 10324 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/init-core-data-001
[junit4] 2> 1421313 T7096 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1421313 T7096 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1421805 T7096 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1
[junit4] 2> 1421808 T7096 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1421812 T7096 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49440
[junit4] 2> 1421813 T7096 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=49440, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores}
[junit4] 2> 1421813 T7096 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1421813 T7096 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/'
[junit4] 2> 1421854 T7096 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/solr.xml
[junit4] 2> 1421885 T7096 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores
[junit4] 2> 1421886 T7096 oasc.CoreContainer.<init> New CoreContainer 1411879948
[junit4] 2> 1421887 T7096 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/]
[junit4] 2> 1421887 T7096 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/lib
[junit4] 2> 1421888 T7096 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/lib).
[junit4] 2> 1421904 T7096 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> 1421909 T7096 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1421910 T7096 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1421910 T7096 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1421911 T7096 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1421914 T7096 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores
[junit4] 2> 1421915 T7096 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1/, configSet=collection1, dataDir=data/}
[junit4] 2> 1421916 T7096 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1/
[junit4] 2> 1421916 T7096 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1421917 T7106 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1421918 T7106 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1421918 T7106 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1421959 T7106 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1421976 T7106 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 1421996 T7106 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1421998 T7106 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 1422005 T7106 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1422091 T7106 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1422098 T7106 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1422099 T7106 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1422104 T7106 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1422108 T7106 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1422109 T7106 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1422110 T7106 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1422110 T7106 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1422110 T7106 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1422111 T7106 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1422111 T7106 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1422111 T7106 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1422111 T7106 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1422112 T7106 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1/data/
[junit4] 2> 1422112 T7106 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@178d64e4
[junit4] 2> 1422112 T7106 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1/data
[junit4] 2> 1422112 T7106 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1/data/index/
[junit4] 2> 1422113 T7106 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1422113 T7106 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/control/cores/collection1/data/index
[junit4] 2> 1422113 T7106 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1422114 T7106 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15d34a60 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@330dc7c4),segFN=segments_1,generation=1}
[junit4] 2> 1422114 T7106 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1422119 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1422119 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1422119 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1422119 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1422120 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1422120 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1422120 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1422120 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1422120 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1422121 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1422121 T7106 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1422121 T7106 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1422129 T7106 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1422132 T7106 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1422135 T7106 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1422144 T7106 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1422156 T7106 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1422161 T7106 c: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> 1422163 T7106 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1422164 T7106 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1422167 T7106 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1422167 T7106 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1422168 T7106 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1422168 T7106 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1422168 T7106 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=53.6982421875, floorSegmentMB=1.150390625, forceMergeDeletesPctAllowed=2.601137154226233, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.42528371034071
[junit4] 2> 1422169 T7106 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15d34a60 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@330dc7c4),segFN=segments_1,generation=1}
[junit4] 2> 1422169 T7106 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1422169 T7106 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@50ab1d1f[collection1] main
[junit4] 2> 1422169 T7106 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1422169 T7106 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1422170 T7106 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1422170 T7106 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1422183 T7106 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1422240 T7106 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1422241 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 1422243 T7106 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1422244 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 1422244 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 1422244 T7096 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1422245 T7096 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1422244 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 1422245 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1422246 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 1422247 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 1422248 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 1422249 T7107 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 1422250 T7107 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50ab1d1f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1422791 T7096 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1422794 T7096 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1422797 T7096 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49444
[junit4] 2> 1422797 T7096 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=49444, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores}
[junit4] 2> 1422797 T7096 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@74a14482
[junit4] 2> 1422798 T7096 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/'
[junit4] 2> 1422832 T7096 oasc.SolrXmlConfig.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1422841 T7096 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores
[junit4] 2> 1422842 T7096 oasc.CoreContainer.<init> New CoreContainer 2105490387
[junit4] 2> 1422842 T7096 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/]
[junit4] 2> 1422842 T7096 oasc.CoreContainer.load loading shared library: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/lib
[junit4] 2> 1422842 T7096 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/lib).
[junit4] 2> 1422852 T7096 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> 1422855 T7096 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1422857 T7096 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1422857 T7096 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1422857 T7096 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1422859 T7096 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores
[junit4] 2> 1422860 T7096 oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1, absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1/, configSet=collection1, dataDir=data/}
[junit4] 2> 1422860 T7096 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1/
[junit4] 2> 1422860 T7096 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1422862 T7118 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1422863 T7118 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1422863 T7118 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1422893 T7118 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1422900 T7118 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.2.0
[junit4] 2> 1422927 T7118 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1422929 T7118 oass.IndexSchema.readSchema Reading Solr Schema from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
[junit4] 2> 1422932 T7118 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1423016 T7118 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1423025 T7118 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1423026 T7118 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1423032 T7118 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1423034 T7118 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1423036 T7118 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1423037 T7118 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1423037 T7118 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1423038 T7118 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1423038 T7118 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1423038 T7118 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1423039 T7118 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 1423039 T7118 c:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1423039 T7118 c:collection1 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1/data/
[junit4] 2> 1423039 T7118 c:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@178d64e4
[junit4] 2> 1423040 T7118 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1/data
[junit4] 2> 1423040 T7118 c:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1/data/index/
[junit4] 2> 1423040 T7118 c:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1423040 T7118 c:collection1 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch 60DB72B4CDACE177-001/tempDir-001/shard0/cores/collection1/data/index
[junit4] 2> 1423041 T7118 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1423041 T7118 c:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5f01e4a2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@629838e0),segFN=segments_1,generation=1}
[junit4] 2> 1423041 T7118 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1423047 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1423047 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1423047 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1423047 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1423047 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1423047 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1423048 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1423048 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1423048 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1423049 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1423049 T7118 c:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1423049 T7118 c:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1423055 T7118 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1423056 T7118 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1423058 T7118 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1423059 T7118 c:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1423065 T7118 c:collection1 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1423067 T7118 c: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> 1423067 T7118 c:collection1 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1423067 T7118 c:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1423070 T7118 c:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1423070 T7118 c:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1423071 T7118 c:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1423072 T7118 c:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1423072 T7118 c:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=53.6982421875, floorSegmentMB=1.150390625, forceMergeDeletesPctAllowed=2.601137154226233, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.42528371034071
[junit4] 2> 1423073 T7118 c:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5f01e4a2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@629838e0),segFN=segments_1,generation=1}
[junit4] 2> 1423073 T7118 c:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1423073 T7118 c:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@30b14fbe[collection1] main
[junit4] 2> 1423074 T7118 c:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1423074 T7118 c:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1423074 T7118 c:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1423074 T7118 c:collection1 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1423079 T7118 c:collection1 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1423117 T7118 c:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1423117 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 1423118 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 1423119 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 1423119 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 1423119 T7118 c:collection1 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1423120 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1423120 T7096 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1423121 T7096 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1423121 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 1423123 T7096 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4] 2> 1423123 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 1423126 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 1423127 T7119 c:collection1 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 1423128 T7119 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30b14fbe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> ASYNC NEW_CORE C1042 name=collection1 org.apache.solr.core.SolrCore@12942961
[junit4] 2> 1423128 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498828623927312384)} 0 3
[junit4] 2> ASYNC NEW_CORE C1043 name=collection1 org.apache.solr.core.SolrCore@967cc55
[junit4] 2> 1423133 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1498828623933603840)} 0 2
[junit4] 2> 1423137 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1498828623939895296)]} 0 0
[junit4] 2> 1423143 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1498828623945138176)]} 0 1
[junit4] 2> 1423146 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1498828623948283904)]} 0 1
[junit4] 2> 1423150 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1498828623952478208)]} 0 1
[junit4] 2> 1423153 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1498828623955623936)]} 0 1
[junit4] 2> 1423155 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1498828623958769664)]} 0 0
[junit4] 2> 1423158 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1498828623961915392)]} 0 0
[junit4] 2> 1423162 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1498828623966109696)]} 0 1
[junit4] 2> 1423165 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1498828623969255424)]} 0 0
[junit4] 2> 1423169 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1498828623973449728)]} 0 1
[junit4] 2> 1423172 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1498828623976595456)]} 0 0
[junit4] 2> 1423174 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1498828623978692608)]} 0 0
[junit4] 2> 1423177 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1498828623981838336)]} 0 0
[junit4] 2> 1423179 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1498828623983935488)]} 0 0
[junit4] 2> 1423182 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1498828623987081216)]} 0 0
[junit4] 2> 1423184 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1498828623989178368)]} 0 0
[junit4] 2> 1423187 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1498828623992324096)]} 0 1
[junit4] 2> 1423189 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1498828623994421248)]} 0 0
[junit4] 2> 1423192 T7103 c:collection1 C1042 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1423197 T7103 c:collection1 C1042 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15d34a60 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@330dc7c4),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15d34a60 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@330dc7c4),segFN=segments_2,generation=2}
[junit4] 2> 1423198 T7103 c:collection1 C1042 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1423202 T7103 c:collection1 C1042 oass.SolrIndexSearcher.<init> Opening Searcher@6fa1012c[collection1] main
[junit4] 2> 1423203 T7103 c:collection1 C1042 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1423208 T7107 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6fa1012c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c9)))}
[junit4] 2> 1423209 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 18
[junit4] 2> 1423211 T7115 c:collection1 C1043 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1423220 T7115 c:collection1 C1043 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5f01e4a2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@629838e0),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5f01e4a2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@629838e0),segFN=segments_2,generation=2}
[junit4] 2> 1423230 T7115 c:collection1 C1043 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1423233 T7115 c:collection1 C1043 oass.SolrIndexSearcher.<init> Opening Searcher@79455fb1[collection1] main
[junit4] 2> 1423234 T7115 c:collection1 C1043 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1423235 T7119 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@79455fb1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c9)))}
[junit4] 2> 1423235 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 24
[junit4] 2> 1423241 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1498828624045801472)]} 0 3
[junit4] 2> 1423253 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1498828624056287232)]} 0 5
[junit4] 2> 1423267 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1498828624075161600)]} 0 1
[junit4] 2> 1423270 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1498828624079355904)]} 0 0
[junit4] 2> 1423283 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1498828624089841664)]} 0 5
[junit4] 2> 1423286 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1498828624096133120)]} 0 0
[junit4] 2> 1423293 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1498828624099278848)]} 0 4
[junit4] 2> 1423301 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1498828624111861760)]} 0 0
[junit4] 2> 1423304 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1498828624115007488)]} 0 0
[junit4] 2> 1423311 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1498828624121298944)]} 0 1
[junit4] 2> 1423314 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1498828624125493248)]} 0 0
[junit4] 2> 1423317 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1498828624127590400)]} 0 1
[junit4] 2> 1423321 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1498828624131784704)]} 0 1
[junit4] 2> 1423323 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1498828624134930432)]} 0 0
[junit4] 2> 1423326 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1498828624138076160)]} 0 1
[junit4] 2> 1423329 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1498828624140173312)]} 0 1
[junit4] 2> 1423332 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1498828624144367616)]} 0 0
[junit4] 2> 1423335 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1498828624147513344)]} 0 1
[junit4] 2> 1423344 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1498828624153804800)]} 0 7
[junit4] 2> 1423350 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1498828624163241984)]} 0 0
[junit4] 2> 1423365 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1498828624174776320)]} 0 10
[junit4] 2> 1423369 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1498828624182116352)]} 0 1
[junit4] 2> 1423371 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1498828624185262080)]} 0 0
[junit4] 2> 1423379 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1498828624189456384)]} 0 5
[junit4] 2> 1423383 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1498828624197844992)]} 0 0
[junit4] 2> 1423390 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1498828624205185024)]} 0 0
[junit4] 2> 1423399 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1498828624214622208)]} 0 5
[junit4] 2> 1423402 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1498828624217767936)]} 0 0
[junit4] 2> 1423405 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1498828624220913664)]} 0 0
[junit4] 2> 1423408 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1498828624224059392)]} 0 1
[junit4] 2> 1423411 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1498828624227205120)]} 0 0
[junit4] 2> 1423414 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1498828624230350848)]} 0 0
[junit4] 2> 1423417 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1498828624232448000)]} 0 1
[junit4] 2> 1423428 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1498828624243982336)]} 0 2
[junit4] 2> 1423431 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1498828624248176640)]} 0 0
[junit4] 2> 1423434 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1498828624250273792)]} 0 1
[junit4] 2> 1423438 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1498828624254468096)]} 0 1
[junit4] 2> 1423442 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1498828624259710976)]} 0 0
[junit4] 2> 1423453 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1498828624261808128)]} 0 9
[junit4] 2> 1423462 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1498828624274391040)]} 0 6
[junit4] 2> 1423465 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1498828624282779648)]} 0 1
[junit4] 2> 1423467 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1498828624285925376)]} 0 0
[junit4] 2> 1423470 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1498828624289071104)]} 0 0
[junit4] 2> 1423472 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1498828624291168256)]} 0 0
[junit4] 2> 1423476 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1498828624295362560)]} 0 1
[junit4] 2> 1423478 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1498828624297459712)]} 0 0
[junit4] 2> 1423481 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1498828624300605440)]} 0 1
[junit4] 2> 1423484 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1498828624303751168)]} 0 1
[junit4] 2> 1423487 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1498828624306896896)]} 0 0
[junit4] 2> 1423490 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1498828624310042624)]} 0 0
[junit4] 2> 1423493 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1498828624313188352)]} 0 0
[junit4] 2> 1423496 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1498828624316334080)]} 0 0
[junit4] 2> 1423499 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1498828624319479808)]} 0 0
[junit4] 2> 1423502 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1498828624322625536)]} 0 0
[junit4] 2> 1423505 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1498828624325771264)]} 0 0
[junit4] 2> 1423508 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1498828624328916992)]} 0 0
[junit4] 2> 1423511 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1498828624332062720)]} 0 0
[junit4] 2> 1423514 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1498828624335208448)]} 0 0
[junit4] 2> 1423517 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1498828624337305600)]} 0 1
[junit4] 2> 1423519 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1498828624340451328)]} 0 0
[junit4] 2> 1423522 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1498828624343597056)]} 0 0
[junit4] 2> 1423525 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1498828624346742784)]} 0 0
[junit4] 2> 1423528 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1498828624349888512)]} 0 0
[junit4] 2> 1423531 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1498828624353034240)]} 0 0
[junit4] 2> 1423534 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1498828624356179968)]} 0 0
[junit4] 2> 1423536 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1498828624358277120)]} 0 0
[junit4] 2> 1423542 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1498828624364568576)]} 0 0
[junit4] 2> 1423545 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1498828624367714304)]} 0 0
[junit4] 2> 1423548 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1498828624370860032)]} 0 0
[junit4] 2> 1423553 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1498828624375054336)]} 0 1
[junit4] 2> 1423556 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1498828624379248640)]} 0 0
[junit4] 2> 1423559 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1498828624382394368)]} 0 0
[junit4] 2> 1423563 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1498828624386588672)]} 0 1
[junit4] 2> 1423565 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1498828624388685824)]} 0 0
[junit4] 2> 1423568 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1498828624391831552)]} 0 0
[junit4] 2> 1423571 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1498828624393928704)]} 0 1
[junit4] 2> 1423573 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1498828624397074432)]} 0 0
[junit4] 2> 1423576 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1498828624400220160)]} 0 0
[junit4] 2> 1423583 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1498828624403365888)]} 0 4
[junit4] 2> 1423591 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1498828624410705920)]} 0 5
[junit4] 2> 1423594 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1498828624419094528)]} 0 1
[junit4] 2> 1423596 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1498828624421191680)]} 0 0
[junit4] 2> 1423599 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1498828624424337408)]} 0 0
[junit4] 2> 1423602 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1498828624427483136)]} 0 0
[junit4] 2> 1423607 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1498828624432726016)]} 0 0
[junit4] 2> 1423610 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1498828624435871744)]} 0 0
[junit4] 2> 1423619 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1498828624442163200)]} 0 3
[junit4] 2> 1423624 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1498828624450551808)]} 0 0
[junit4] 2> 1423627 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1498828624453697536)]} 0 0
[junit4] 2> 1423630 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1498828624456843264)]} 0 0
[junit4] 2> 1423633 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1498828624459988992)]} 0 0
[junit4] 2> 1423636 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1498828624463134720)]} 0 1
[junit4] 2> 1423639 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1498828624465231872)]} 0 1
[junit4] 2> 1423641 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1498828624468377600)]} 0 0
[junit4] 2> 1423643 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1498828624470474752)]} 0 0
[junit4] 2> 1423646 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1498828624473620480)]} 0 0
[junit4] 2> 1423648 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1498828624475717632)]} 0 0
[junit4] 2> 1423650 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1498828624477814784)]} 0 0
[junit4] 2> 1423652 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1498828624479911936)]} 0 0
[junit4] 2> 1423655 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1498828624483057664)]} 0 0
[junit4] 2> 1423657 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1498828624485154816)]} 0 0
[junit4] 2> 1423659 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1498828624487251968)]} 0 0
[junit4] 2> 1423662 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1498828624490397696)]} 0 0
[junit4] 2> 1423664 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1498828624492494848)]} 0 0
[junit4] 2> 1423667 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1498828624494592000)]} 0 1
[junit4] 2> 1423669 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1498828624497737728)]} 0 0
[junit4] 2> 1423672 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1498828624499834880)]} 0 1
[junit4] 2> 1423674 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1498828624502980608)]} 0 1
[junit4] 2> 1423677 T7102 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1498828624506126336)]} 0 0
[junit4] 2> 1423680 T7112 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1498828624509272064)]} 0 1
[junit4] 2> 1423683 T7100 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1498828624512417792)]} 0 0
[junit4] 2> 1423686 T7116 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1498828624515563520)]} 0 0
[junit4] 2> 1423692 T7104 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1498828624518709248)]} 0 3
[junit4] 2> 1423705 T7113 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1498828624532340736)]} 0 3
[junit4] 2> 1423709 T7103 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1498828624538632192)]} 0 1
[junit4] 2> 1423712 T7117 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1498828624542826496)]} 0 1
[junit4] 2> 1423715 T7105 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1498828624544923648)]} 0 1
[junit4] 2> 1423718 T7115 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1498828624549117952)]} 0 0
[junit4] 2> 1423721 T7101 c:collection1 C1042 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1423730 T7101 c:collection1 C1042 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15d34a60 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@330dc7c4),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15d34a60 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@330dc7c4),segFN=segments_3,generation=3}
[junit4] 2> 1423730 T7101 c:collection1 C1042 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1423731 T7101 c:collection1 C1042 oass.SolrIndexSearcher.<init> Opening Searcher@15dfbfd[collection1] main
[junit4] 2> 1423731 T7101 c:collection1 C1042 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1423734 T7107 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15dfbfd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c9) Uninverting(_1(5.2.0):c20) Uninverting(_2(5.2.0):c20) Uninverting(_3(5.2.0):c19)))}
[junit4] 2> 1423735 T7101 c:collection1 C1042 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 15
[junit4] 2> 1423737 T7114 c:collection1 C1043 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1423756 T7114 c:collection1 C1043 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5f01e4a2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@629838e0),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5f01e4a2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@629838e0),segFN=segments_3,generation=3}
[junit4] 2> 1423756 T7114 c:collection1 C1043 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1423757 T7114 c:collection1 C1043 oass.SolrIndexSearcher.<init> Opening Searcher@19561613[collection1] main
[junit4] 2> 1423758 T7114 c:collection1 C1043 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1423759 T7119 c:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19561613[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.2.0):c9) Uninverting(_1(5.2.0):c20) Uninverting(_2(5.2.0):c20) Uninverting(_3(5.2.0):c19)))}
[junit4] 2> 1423759 T7114 c:collection1 C1043 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 22
[junit4] 2> 1423781 T7102 c:collection1 C1042 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: 'start' parameter cannot be negative
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:228)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:229)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1988)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1423783 T7102 c:collection1 C1042 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=20
[junit4] 2> 1423786 T7100 c:collection1 C1042 oasc.SolrException.log ERROR org.apache.solr.common.SolrException: 'rows' parameter cannot be negative
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:222)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:229)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1988)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:841)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:453)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:223)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:104)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.ja
[...truncated too long message...]
4] 2> 1647686 T7340 N:127.0.0.1:49548_ oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1647708 T7340 N:127.0.0.1:49548_ oasc.CachingDirectoryFactory.close Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1647708 T7340 N:127.0.0.1:49548_ oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest 60DB72B4CDACE177-001/shard-3-001/cores/collection1/data [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest 60DB72B4CDACE177-001/shard-3-001/cores/collection1/data;done=false>>]
[junit4] 2> 1647708 T7340 N:127.0.0.1:49548_ oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest 60DB72B4CDACE177-001/shard-3-001/cores/collection1/data
[junit4] 2> 1647708 T7340 N:127.0.0.1:49548_ oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest 60DB72B4CDACE177-001/shard-3-001/cores/collection1/data/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest 60DB72B4CDACE177-001/shard-3-001/cores/collection1/data/index;done=false>>]
[junit4] 2> 1647709 T7340 N:127.0.0.1:49548_ oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest 60DB72B4CDACE177-001/shard-3-001/cores/collection1/data/index
[junit4] 2> 1647725 T7219 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 1647914 T7219 C:control_collection S:shard1 c:collection1 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:49531 49531
[junit4] 2> 1647918 T7344 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:49531 49531
[junit4] 2> 1649074 T7344 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ChaosMonkeyNothingIsSafeTest -Dtests.method=test -Dtests.seed=60DB72B4CDACE177 -Dtests.slow=true -Dtests.locale=en_AU -Dtests.timezone=Etc/Zulu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 52.9s J1 | ChaosMonkeyNothingIsSafeTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: There were too many update fails (28 > 20) - we expect it can happen, but shouldn't easily
[junit4] > at __randomizedtesting.SeedInfo.seed([60DB72B4CDACE177:E88F4D6E63508C8F]:0)
[junit4] > at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test(ChaosMonkeyNothingIsSafeTest.java:230)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1649093 T7219 C:control_collection S:shard1 c:collection1 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest 60DB72B4CDACE177-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {rnd_b=PostingsFormat(name=LuceneFixedGap), _version_=PostingsFormat(name=Memory doPackFST= false), a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_i=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, sim=DefaultSimilarity, locale=en_AU, timezone=Etc/Zulu
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_40 (64-bit)/cpus=3,threads=1,free=254798520,total=404226048
[junit4] 2> NOTE: All tests run in this JVM: [TestReqParamsAPI, HighlighterTest, DirectUpdateHandlerOptimizeTest, TestRangeQuery, SpellingQueryConverterTest, TestSerializedLuceneMatchVersion, QueryResultKeyTest, TestCoreDiscovery, BinaryUpdateRequestHandlerTest, TestObjectReleaseTracker, CurrencyFieldXmlFileTest, TestSolr4Spatial, TestSearchPerf, TestSystemIdResolver, RAMDirectoryFactoryTest, TestAnalyzeInfixSuggestions, DistribJoinFromCollectionTest, TestSchemaNameResource, TestLMDirichletSimilarityFactory, TestCloudSchemaless, InfoHandlerTest, AsyncMigrateRouteKeyTest, DistributedFacetPivotSmallAdvancedTest, CopyFieldTest, TestDFRSimilarityFactory, TestRemoteStreaming, FastVectorHighlighterTest, HighlighterConfigTest, JsonLoaderTest, TestRealTimeGet, TestStandardQParsers, EchoParamsTest, TestDocBasedVersionConstraints, TestReload, TestBulkSchemaConcurrent, DocExpirationUpdateProcessorFactoryTest, NotRequiredUniqueKeyTest, DistributedFacetPivotLargeTest, DisMaxRequestHandlerTest, TestConfigReload, TestReversedWildcardFilterFactory, TestCollapseQParserPlugin, SchemaVersionSpecificBehaviorTest, TestDocSet, DistribCursorPagingTest, TestLeaderElectionZkExpiry, BasicDistributedZk2Test, TestZkChroot, TestUtils, TestRandomFaceting, TestSortingResponseWriter, CollectionsAPIDistributedZkTest, IndexBasedSpellCheckerTest, SegmentsInfoRequestHandlerTest, FileBasedSpellCheckerTest, TestFieldTypeCollectionResource, TestSimpleTrackingShardHandler, RollingRestartTest, HdfsSyncSliceTest, BadIndexSchemaTest, TestLMJelinekMercerSimilarityFactory, MBeansHandlerTest, TestFoldingMultitermQuery, TestLRUStatsCache, TestCopyFieldCollectionResource, ZkSolrClientTest, TestStressUserVersions, DistributedTermsComponentTest, ResponseHeaderTest, StandardRequestHandlerTest, CurrencyFieldOpenExchangeTest, HdfsLockFactoryTest, SimplePostToolTest, TestManagedSchemaFieldTypeResource, TestDefaultStatsCache, TestAddFieldRealTimeGet, TestRawResponseWriter, LeaderElectionIntegrationTest, TestImplicitCoreProperties, MoreLikeThisHandlerTest, QueryParsingTest, AutoCommitTest, SolrIndexConfigTest, TestSolrDeletionPolicy2, TestSolrQueryParserDefaultOperatorResource, TestInitParams, TestValueSourceCache, TestSolrQueryParserResource, TestExactStatsCache, TestBadConfig, SignatureUpdateProcessorFactoryTest, TestCodecSupport, HdfsThreadLeakTest, TestSolrConfigHandler, TermsComponentTest, TestRTGBase, ResourceLoaderTest, OutOfBoxZkACLAndCredentialsProvidersTest, HttpPartitionTest, TestDistributedGrouping, SuggesterTSTTest, PrimitiveFieldTypeTest, DebugComponentTest, AliasIntegrationTest, TestRecovery, TestPivotHelperCode, TestReplicationHandlerBackup, FullSolrCloudDistribCmdsTest, XsltUpdateRequestHandlerTest, TestMergePolicyConfig, ParsingFieldUpdateProcessorsTest, TestFastOutputStream, TestPHPSerializedResponseWriter, DeleteInactiveReplicaTest, TestOverriddenPrefixQueryForCustomFieldType, SpellCheckComponentTest, ClusterStateUpdateTest, LeaderElectionTest, ShardRoutingCustomTest, TestHashPartitioner, TermVectorComponentDistributedTest, TestJoin, TestStressVersions, SolrCoreTest, StatsComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, TestFiltering, OverseerCollectionProcessorTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, TestExtendedDismaxParser, SuggesterFSTTest, SolrRequestParserTest, TestStressLucene, TestTrie, SpatialFilterTest, PolyFieldTest, SolrCoreCheckLockOnStartupTest, TestAtomicUpdateErrorCases, StatelessScriptUpdateProcessorFactoryTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, DocumentBuilderTest, FieldAnalysisRequestHandlerTest, TestArbitraryIndexDir, RegexBoostProcessorTest, TestCollationField, UpdateRequestProcessorFactoryTest, TestAnalyzedSuggestions, CSVRequestHandlerTest, TestQuerySenderListener, TestQuerySenderNoQuery, ResponseLogComponentTest, TestBinaryField, TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, SpellPossibilityIteratorTest, TestXIncludeConfig, TestDefaultSimilarityFactory, ScriptEngineTest, URLClassifyProcessorTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest]
[junit4] Completed [358/483] on J1 in 52.96s, 1 test, 1 failure <<< FAILURES!
[...truncated 405 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:536: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:484: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:229: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:511: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1434: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:991: There were test failures: 483 suites, 1917 tests, 1 error, 1 failure, 56 ignored (22 assumptions)
Total time: 67 minutes 33 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any