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/03/12 14:31:21 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_31) - Build #
4543 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4543/
Java: 32bit/jdk1.8.0_31 -server -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Error from server at http://127.0.0.1:56034//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:738) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:721) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:359) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:142) at org.apache.solr.core.SolrCore.execute(SolrCore.java:1967) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:808) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:435) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:218) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:103) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) at org.eclipse.jetty.server.Server.handle(Server.java:497) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:56034//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:738)
at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:721)
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:359)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:142)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1967)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:808)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:435)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:218)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:103)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
at org.eclipse.jetty.server.Server.handle(Server.java:497)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([A4AFEDA73FFF081C:2CFBD27D910365E4]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:584)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:236)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:228)
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:978)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:940)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:813)
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:483)
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:980)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.ReplicationFactorTest.test
Error Message:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:58378/repfacttest_c8n_1x3_shard1_replica3
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:58378/repfacttest_c8n_1x3_shard1_replica3
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:625)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:948)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:839)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:782)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:284)
at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:112)
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:483)
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:958)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:58378/repfacttest_c8n_1x3_shard1_replica3
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:599)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:236)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:228)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:622)
... 47 more
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:490)
... 52 more
Build Log:
[...truncated 9261 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\init-core-data-001
[junit4] 2> 1332207 T6925 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1332207 T6925 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1334551 T6925 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1
[junit4] 2> 1334558 T6925 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1334562 T6925 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@135ab08{/,null,AVAILABLE}
[junit4] 2> 1334566 T6925 oejs.AbstractConnector.doStart Started ServerConnector@e0436a{HTTP/1.1}{127.0.0.1:55994}
[junit4] 2> 1334566 T6925 oejs.Server.doStart Started @1338461ms
[junit4] 2> 1334566 T6925 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=55994, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores}
[junit4] 2> 1334566 T6925 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44
[junit4] 2> 1334566 T6925 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\'
[junit4] 2> 1334631 T6925 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\solr.xml
[junit4] 2> 1334649 T6925 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores
[junit4] 2> 1334649 T6925 oasc.CoreContainer.<init> New CoreContainer 4847938
[junit4] 2> 1334652 T6925 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\]
[junit4] 2> 1334652 T6925 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\lib
[junit4] 2> 1334652 T6925 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\lib).
[junit4] 2> 1334666 T6925 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1334666 T6925 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1334666 T6925 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1334666 T6925 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1334666 T6925 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1334666 T6925 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1334666 T6925 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1334666 T6925 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1334670 T6925 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1334670 T6925 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1334670 T6925 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1334670 T6925 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1334670 T6925 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1334670 T6925 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1334670 T6925 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1334676 T6925 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1\, configSet=collection1, dataDir=data\}
[junit4] 2> 1334678 T6925 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1\
[junit4] 2> 1334678 T6925 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1334680 T6937 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 1334683 T6937 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1334684 T6937 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1334684 T6937 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1334735 T6937 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1334747 T6937 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1334776 T6937 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1334778 T6937 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 1334787 T6937 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1334957 T6937 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1334972 T6937 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1334975 T6937 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1334987 T6937 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1334993 T6937 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1334997 T6937 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1334997 T6937 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1334997 T6937 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1334999 T6937 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1334999 T6937 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1334999 T6937 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1334999 T6937 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 1335002 T6937 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1335003 T6937 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1\data\
[junit4] 2> 1335003 T6937 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fd3bee
[junit4] 2> 1335004 T6937 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1\data\
[junit4] 2> 1335004 T6937 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1\data\index/
[junit4] 2> 1335004 T6937 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1335005 T6937 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\control\cores\collection1\data\index
[junit4] 2> 1335005 T6937 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1335006 T6937 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d58787 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dc8dd2),segFN=segments_1,generation=1}
[junit4] 2> 1335006 T6937 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1335015 T6937 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1335015 T6937 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1335015 T6937 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1335016 T6937 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1335016 T6937 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1335017 T6937 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1335017 T6937 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1335019 T6937 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1335019 T6937 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1335020 T6937 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1335020 T6937 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1335020 T6937 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1335032 T6937 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1335034 T6937 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1335038 T6937 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1335040 T6937 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1335048 T6937 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1335052 T6937 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> 1335052 T6937 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1335052 T6937 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1335056 T6937 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1335056 T6937 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1335059 T6937 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1335059 T6937 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1335060 T6937 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=56.5048828125, floorSegmentMB=1.67578125, forceMergeDeletesPctAllowed=21.699715806707896, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1335061 T6937 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d58787 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dc8dd2),segFN=segments_1,generation=1}
[junit4] 2> 1335061 T6937 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1335062 T6937 oass.SolrIndexSearcher.<init> Opening Searcher@1ecaaf4[collection1] main
[junit4] 2> 1335063 T6937 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1335063 T6937 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1335063 T6937 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1335063 T6937 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1335101 T6937 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1335160 T6937 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1335160 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 1335160 T6937 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1335160 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 1335162 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 1335162 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 1335162 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1335162 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 1335163 T6925 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1335165 T6925 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1335163 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 1335165 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 1335165 T6938 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 1335165 T6938 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ecaaf4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1337154 T6925 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 1337158 T6925 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 1337173 T6925 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@fab400{/,null,AVAILABLE}
[junit4] 2> 1337176 T6925 oejs.AbstractConnector.doStart Started ServerConnector@18053b0{HTTP/1.1}{127.0.0.1:55997}
[junit4] 2> 1337176 T6925 oejs.Server.doStart Started @1340793ms
[junit4] 2> 1337176 T6925 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {hostContext=/, hostPort=55997, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores}
[junit4] 2> 1337176 T6925 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44
[junit4] 2> 1337176 T6925 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\'
[junit4] 2> 1337218 T6925 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\solr.xml
[junit4] 2> 1337228 T6925 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores
[junit4] 2> 1337228 T6925 oasc.CoreContainer.<init> New CoreContainer 2938594
[junit4] 2> 1337228 T6925 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\]
[junit4] 2> 1337228 T6925 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\lib
[junit4] 2> 1337231 T6925 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\lib).
[junit4] 2> 1337246 T6925 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1337246 T6925 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1337246 T6925 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1337246 T6925 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1337246 T6925 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1337246 T6925 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1337246 T6925 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1337248 T6925 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1337248 T6925 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1337248 T6925 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1337248 T6925 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 1337248 T6925 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1337248 T6925 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1337250 T6925 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1337250 T6925 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 1337254 T6925 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores
[junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1\, configSet=collection1, dataDir=data\}
[junit4] 2> 1337254 T6925 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1\
[junit4] 2> 1337254 T6925 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1337256 T6951 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 1337258 T6951 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1337258 T6951 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1337258 T6951 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1337305 T6951 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1337312 T6951 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1337330 T6951 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1337330 T6951 oass.IndexSchema.readSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 1337336 T6951 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1337461 T6951 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1337474 T6951 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1337475 T6951 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1337484 T6951 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1337487 T6951 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1337490 T6951 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1337490 T6951 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1337490 T6951 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1337490 T6951 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1337490 T6951 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1337492 T6951 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1337492 T6951 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from configset C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 1337492 T6951 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1337494 T6951 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1\data\
[junit4] 2> 1337494 T6951 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fd3bee
[junit4] 2> 1337495 T6951 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1\data\
[junit4] 2> 1337495 T6951 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1\data\index/
[junit4] 2> 1337495 T6951 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1337495 T6951 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch A4AFEDA73FFF081C-001\tempDir-001\shard0\cores\collection1\data\index
[junit4] 2> 1337496 T6951 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1337496 T6951 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9af4a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e51cd0),segFN=segments_1,generation=1}
[junit4] 2> 1337496 T6951 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1337503 T6951 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1337504 T6951 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1337504 T6951 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1337504 T6951 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1337504 T6951 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1337504 T6951 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1337504 T6951 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1337505 T6951 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1337505 T6951 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1337505 T6951 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1337506 T6951 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1337506 T6951 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1337510 T6951 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1337514 T6951 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1337517 T6951 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1337521 T6951 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1337528 T6951 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1337532 T6951 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> 1337532 T6951 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1337532 T6951 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1337534 T6951 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1337534 T6951 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 1337537 T6951 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1337537 T6951 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1337538 T6951 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=56.5048828125, floorSegmentMB=1.67578125, forceMergeDeletesPctAllowed=21.699715806707896, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1337538 T6951 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9af4a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e51cd0),segFN=segments_1,generation=1}
[junit4] 2> 1337538 T6951 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1337539 T6951 oass.SolrIndexSearcher.<init> Opening Searcher@16315d7[collection1] main
[junit4] 2> 1337539 T6951 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1337540 T6951 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1337540 T6951 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1337540 T6951 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1337557 T6951 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1337615 T6951 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1337617 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default
[junit4] 2> 1337617 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct
[junit4] 2> 1337617 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak
[junit4] 2> 1337617 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields
[junit4] 2> 1337617 T6951 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1337619 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1337620 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external
[junit4] 2> 1337620 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq
[junit4] 2> 1337621 T6925 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1337621 T6925 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1337621 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn
[junit4] 2> 1337622 T6925 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4] 2> 1337623 T6952 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict
[junit4] 2> 1337624 T6952 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16315d7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> ASYNC NEW_CORE C967 name=collection1 org.apache.solr.core.SolrCore@14312f
[junit4] 2> 1337640 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1495440301813137408)} 0 2
[junit4] 2> ASYNC NEW_CORE C968 name=collection1 org.apache.solr.core.SolrCore@b13652
[junit4] 2> 1337649 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1495440301821526016)} 0 3
[junit4] 2> 1337654 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1495440301827817472)]} 0 2
[junit4] 2> 1337667 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1495440301841448960)]} 0 2
[junit4] 2> 1337672 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1495440301846691840)]} 0 2
[junit4] 2> 1337677 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[2 (1495440301852983296)]} 0 1
[junit4] 2> 1337697 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1495440301861371904)]} 0 13
[junit4] 2> 1337763 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[3 (1495440301897023488)]} 0 45
[junit4] 2> 1337769 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1495440301950500864)]} 0 0
[junit4] 2> 1337778 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[4 (1495440301956792320)]} 0 3
[junit4] 2> 1337785 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1495440301966229504)]} 0 1
[junit4] 2> 1337791 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1495440301972520960)]} 0 1
[junit4] 2> 1337799 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1495440301979860992)]} 0 2
[junit4] 2> 1337804 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[6 (1495440301987201024)]} 0 0
[junit4] 2> 1337811 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1495440301994541056)]} 0 0
[junit4] 2> 1337817 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1495440302000832512)]} 0 0
[junit4] 2> 1337824 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1495440302008172544)]} 0 0
[junit4] 2> 1337831 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[8 (1495440302015512576)]} 0 0
[junit4] 2> 1337836 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1495440302020755456)]} 0 0
[junit4] 2> 1337841 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[9 (1495440302025998336)]} 0 0
[junit4] 2> 1337847 T6932 C967 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1337851 T6932 C967 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d58787 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dc8dd2),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d58787 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dc8dd2),segFN=segments_2,generation=2}
[junit4] 2> 1337855 T6932 C967 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1337893 T6932 C967 oass.SolrIndexSearcher.<init> Opening Searcher@13bfeb1[collection1] main
[junit4] 2> 1337894 T6932 C967 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1337894 T6938 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13bfeb1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9)))}
[junit4] 2> 1337897 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 50
[junit4] 2> 1337902 T6947 C968 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1337910 T6947 C968 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9af4a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e51cd0),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9af4a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e51cd0),segFN=segments_2,generation=2}
[junit4] 2> 1337914 T6947 C968 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1337919 T6947 C968 oass.SolrIndexSearcher.<init> Opening Searcher@1d40498[collection1] main
[junit4] 2> 1337919 T6947 C968 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1337921 T6952 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d40498[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9)))}
[junit4] 2> 1337922 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 20
[junit4] 2> 1337940 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1495440302118273024)]} 0 11
[junit4] 2> 1337958 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[10 (1495440302136098816)]} 0 12
[junit4] 2> 1337965 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1495440302156021760)]} 0 0
[junit4] 2> 1337972 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[11 (1495440302163361792)]} 0 0
[junit4] 2> 1337978 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1495440302169653248)]} 0 0
[junit4] 2> 1337984 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[12 (1495440302175944704)]} 0 1
[junit4] 2> 1337987 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1495440302179090432)]} 0 0
[junit4] 2> 1337995 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[13 (1495440302187479040)]} 0 0
[junit4] 2> 1338001 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1495440302193770496)]} 0 0
[junit4] 2> 1338010 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1001 (1495440302202159104)]} 0 1
[junit4] 2> 1338014 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1495440302207401984)]} 0 0
[junit4] 2> 1338020 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[14 (1495440302213693440)]} 0 0
[junit4] 2> 1338027 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1495440302221033472)]} 0 0
[junit4] 2> 1338033 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[15 (1495440302227324928)]} 0 0
[junit4] 2> 1338038 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1495440302232567808)]} 0 0
[junit4] 2> 1338042 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[16 (1495440302236762112)]} 0 0
[junit4] 2> 1338055 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1495440302246199296)]} 0 4
[junit4] 2> 1338060 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[17 (1495440302255636480)]} 0 0
[junit4] 2> 1338068 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1495440302264025088)]} 0 0
[junit4] 2> 1338075 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[100 (1495440302270316544)]} 0 1
[junit4] 2> 1338082 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1495440302278705152)]} 0 1
[junit4] 2> 1338086 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[101 (1495440302281850880)]} 0 1
[junit4] 2> 1338092 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1495440302289190912)]} 0 0
[junit4] 2> 1338097 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[102 (1495440302294433792)]} 0 0
[junit4] 2> 1338104 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1495440302301773824)]} 0 0
[junit4] 2> 1338109 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[103 (1495440302307016704)]} 0 0
[junit4] 2> 1338129 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1495440302311211008)]} 0 16
[junit4] 2> 1338133 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[104 (1495440302332182528)]} 0 0
[junit4] 2> 1338140 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1495440302339522560)]} 0 0
[junit4] 2> 1338147 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[105 (1495440302346862592)]} 0 0
[junit4] 2> 1338155 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1495440302355251200)]} 0 0
[junit4] 2> 1338162 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[106 (1495440302362591232)]} 0 0
[junit4] 2> 1338168 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1495440302368882688)]} 0 0
[junit4] 2> 1338174 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[107 (1495440302375174144)]} 0 0
[junit4] 2> 1338181 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1495440302382514176)]} 0 0
[junit4] 2> 1338187 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[108 (1495440302388805632)]} 0 0
[junit4] 2> 1338194 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1495440302396145664)]} 0 0
[junit4] 2> 1338201 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[109 (1495440302403485696)]} 0 0
[junit4] 2> 1338210 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1495440302412922880)]} 0 0
[junit4] 2> 1338215 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[110 (1495440302418165760)]} 0 0
[junit4] 2> 1338221 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1495440302424457216)]} 0 0
[junit4] 2> 1338227 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[111 (1495440302430748672)]} 0 0
[junit4] 2> 1338233 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1495440302437040128)]} 0 0
[junit4] 2> 1338240 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[112 (1495440302444380160)]} 0 0
[junit4] 2> 1338255 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1495440302459060224)]} 0 1
[junit4] 2> 1338258 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[113 (1495440302463254528)]} 0 0
[junit4] 2> 1338265 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1495440302470594560)]} 0 0
[junit4] 2> 1338271 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[114 (1495440302476886016)]} 0 0
[junit4] 2> 1338277 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1495440302483177472)]} 0 0
[junit4] 2> 1338282 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[115 (1495440302488420352)]} 0 0
[junit4] 2> 1338289 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1495440302495760384)]} 0 0
[junit4] 2> 1338296 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[116 (1495440302503100416)]} 0 0
[junit4] 2> 1338302 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1495440302509391872)]} 0 0
[junit4] 2> 1338310 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[117 (1495440302517780480)]} 0 0
[junit4] 2> 1338313 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1495440302520926208)]} 0 0
[junit4] 2> 1338318 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[118 (1495440302526169088)]} 0 0
[junit4] 2> 1338326 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1495440302534557696)]} 0 0
[junit4] 2> 1338332 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[119 (1495440302540849152)]} 0 0
[junit4] 2> 1338339 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1495440302548189184)]} 0 0
[junit4] 2> 1338346 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[120 (1495440302553432064)]} 0 2
[junit4] 2> 1338350 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1495440302559723520)]} 0 0
[junit4] 2> 1338358 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[121 (1495440302568112128)]} 0 0
[junit4] 2> 1338367 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1495440302574403584)]} 0 3
[junit4] 2> 1338374 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[122 (1495440302583840768)]} 0 1
[junit4] 2> 1338383 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1495440302593277952)]} 0 1
[junit4] 2> 1338387 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[123 (1495440302598520832)]} 0 0
[junit4] 2> 1338394 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1495440302605860864)]} 0 0
[junit4] 2> 1338400 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[124 (1495440302612152320)]} 0 0
[junit4] 2> 1338408 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1495440302620540928)]} 0 0
[junit4] 2> 1338414 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1495440302626832384)]} 0 0
[junit4] 2> 1338422 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1495440302635220992)]} 0 0
[junit4] 2> 1338429 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[126 (1495440302642561024)]} 0 0
[junit4] 2> 1338438 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1495440302650949632)]} 0 1
[junit4] 2> 1338444 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[127 (1495440302656192512)]} 0 2
[junit4] 2> 1338451 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1495440302665629696)]} 0 0
[junit4] 2> 1338457 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[128 (1495440302671921152)]} 0 0
[junit4] 2> 1338463 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1495440302678212608)]} 0 0
[junit4] 2> 1338467 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[129 (1495440302682406912)]} 0 0
[junit4] 2> 1338475 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1495440302689746944)]} 0 1
[junit4] 2> 1338480 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[130 (1495440302693941248)]} 0 2
[junit4] 2> 1338484 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1495440302699184128)]} 0 1
[junit4] 2> 1338489 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[131 (1495440302703378432)]} 0 2
[junit4] 2> 1338492 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1495440302708621312)]} 0 0
[junit4] 2> 1338500 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[132 (1495440302717009920)]} 0 0
[junit4] 2> 1338504 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1495440302721204224)]} 0 0
[junit4] 2> 1338508 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[133 (1495440302725398528)]} 0 0
[junit4] 2> 1338511 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1495440302728544256)]} 0 0
[junit4] 2> 1338514 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[134 (1495440302731689984)]} 0 0
[junit4] 2> 1338520 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1495440302737981440)]} 0 0
[junit4] 2> 1338524 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[135 (1495440302742175744)]} 0 0
[junit4] 2> 1338529 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1495440302747418624)]} 0 0
[junit4] 2> 1338546 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[136 (1495440302765244416)]} 0 0
[junit4] 2> 1338550 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1495440302769438720)]} 0 0
[junit4] 2> 1338556 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[137 (1495440302774681600)]} 0 1
[junit4] 2> 1338560 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1495440302779924480)]} 0 0
[junit4] 2> 1338569 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[138 (1495440302789361664)]} 0 0
[junit4] 2> 1338573 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1495440302793555968)]} 0 0
[junit4] 2> 1338580 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[139 (1495440302798798848)]} 0 2
[junit4] 2> 1338584 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1495440302805090304)]} 0 0
[junit4] 2> 1338589 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[140 (1495440302810333184)]} 0 0
[junit4] 2> 1338594 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1495440302814527488)]} 0 1
[junit4] 2> 1338598 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[141 (1495440302819770368)]} 0 0
[junit4] 2> 1338602 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1495440302823964672)]} 0 0
[junit4] 2> 1338607 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[142 (1495440302829207552)]} 0 2
[junit4] 2> 1338610 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1495440302832353280)]} 0 0
[junit4] 2> 1338613 T6946 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[143 (1495440302835499008)]} 0 0
[junit4] 2> 1338618 T6932 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1495440302840741888)]} 0 0
[junit4] 2> 1338621 T6947 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[144 (1495440302843887616)]} 0 0
[junit4] 2> 1338627 T6934 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1495440302850179072)]} 0 0
[junit4] 2> 1338630 T6948 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[145 (1495440302853324800)]} 0 0
[junit4] 2> 1338633 T6929 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1495440302856470528)]} 0 0
[junit4] 2> 1338637 T6943 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[146 (1495440302860664832)]} 0 0
[junit4] 2> 1338641 T6930 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1495440302864859136)]} 0 0
[junit4] 2> 1338646 T6944 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[147 (1495440302870102016)]} 0 0
[junit4] 2> 1338653 T6931 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1495440302877442048)]} 0 0
[junit4] 2> 1338657 T6945 C968 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[148 (1495440302881636352)]} 0 0
[junit4] 2> 1338662 T6933 C967 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1495440302886879232)]} 0 0
[junit4] 2> ASYNC NEW_CORE C969 name=collection1 org.apache.solr.core.SolrCore@b13652
[junit4] 2> 1338689 T6946 C969 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[149 (1495440302891073536)]} 0 23
[junit4] 2> ASYNC NEW_CORE C970 name=collection1 org.apache.solr.core.SolrCore@14312f
[junit4] 2> 1338692 T6932 C970 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1338704 T6932 C970 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d58787 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dc8dd2),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d58787 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dc8dd2),segFN=segments_3,generation=3}
[junit4] 2> 1338705 T6932 C970 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1338707 T6932 C970 oass.SolrIndexSearcher.<init> Opening Searcher@1e4f52c[collection1] main
[junit4] 2> 1338707 T6932 C970 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1338709 T6938 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e4f52c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9) Uninverting(_1(6.0.0):c59)))}
[junit4] 2> 1338712 T6932 C970 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 20
[junit4] 2> 1338714 T6947 C969 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1338727 T6947 C969 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9af4a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e51cd0),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@9af4a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e51cd0),segFN=segments_3,generation=3}
[junit4] 2> 1338727 T6947 C969 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 1338728 T6947 C969 oass.SolrIndexSearcher.<init> Opening Searcher@18e5da0[collection1] main
[junit4] 2> 1338728 T6947 C969 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1338731 T6952 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18e5da0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c9) Uninverting(_1(6.0.0):c59)))}
[junit4] 2> 1338732 T6947 C969 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 18
[junit4] 2> 1338737 T6934 C970 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1338764 T6943 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:55997//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1426163008662&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1338772 T6944 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1426163008662&ids=102,124,146,4,137,127,149,116,128,142&isShard=true&shard.url=http://127.0.0.1:55997//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1338775 T6948 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:55997//collection1|[ff01::114]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=33
[junit4] 2> 1338778 T6929 C970 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1338792 T6946 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:55997//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1426163008701&isShard=true&wt=javabin} hits=68 status=0 QTime=2
[junit4] 2> 1338798 T6947 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1426163008701&ids=143,112,15,16,1001,7,8,108,130,131&isShard=true&shard.url=http://127.0.0.1:55997//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1338804 T6945 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:55997//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=23
[junit4] 2> 1338811 T6930 C970 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1338820 T6944 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:55997//collection1&rows=10&version=2&q=*:*&NOW=1426163008737&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1338828 T6948 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1426163008737&ids=143,132,144,137,5,138,17,119,140,10&isShard=true&shard.url=http://127.0.0.1:55997//collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1338842 T6943 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:55997//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=25
[junit4] 2> 1338851 T6931 C970 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 1338863 T6947 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:55997//collection1&rows=10&version=2&q=*:*&NOW=1426163008777&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1338869 T6945 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1426163008777&ids=113,3,146,124,103,16,1001,129,131,142&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:55997//collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1338871 T6946 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:55997//collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=14
[junit4] 2> 1338878 T6933 C970 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2
[junit4] 2> 1338889 T6948 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:55997//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1426163008803&isShard=true&wt=javabin} hits=68 status=0 QTime=2
[junit4] 2> 1338896 T6943 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1426163008803&ids=11,113,146,114,4,105,116,117,107,119&isShard=true&shard.url=http://127.0.0.1:55997//collection1|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1338900 T6944 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:55997//collection1|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=17
[junit4] 2> 1338904 T6932 C970 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1338919 T6945 C969 oasc.SolrCore.execute [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/|[ff01
[...truncated too long message...]
RequestExecutor.java:271)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:490)
[junit4] > ... 52 moreThrowable #2: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=13308, name=updateExecutor-1532-thread-3, state=RUNNABLE, group=TGRP-ReplicationFactorTest]
[junit4] > Caused by: java.util.concurrent.RejectedExecutionException: Task org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner@d0e82 rejected from java.util.concurrent.ThreadPoolExecutor@1bf6a44[Shutting down, pool size = 1, active threads = 1, queued tasks = 0, completed tasks = 23]
[junit4] > at __randomizedtesting.SeedInfo.seed([A4AFEDA73FFF081C]:0)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
[junit4] > at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.run(ConcurrentUpdateSolrClient.java:279)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2821254 T13124 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest A4AFEDA73FFF081C-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {_version_=PostingsFormat(name=Memory doPackFST= false), multiDefault=PostingsFormat(name=Direct), a_t=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=Memory doPackFST= false), id=Lucene50(blocksize=128), text=FSTOrd50, range_facet_l=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false)}, docValues:{timestamp=DocValuesFormat(name=Direct)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=ar_MA, timezone=Europe/Bratislava
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_31 (32-bit)/cpus=2,threads=1,free=140620824,total=268062720
[junit4] 2> NOTE: All tests run in this JVM: [ClusterStateUpdateTest, QueryElevationComponentTest, SaslZkACLProviderTest, FullSolrCloudDistribCmdsTest, TestBinaryResponseWriter, TestSolr4Spatial2, MoreLikeThisHandlerTest, OverseerCollectionProcessorTest, TestCollapseQParserPlugin, DocumentAnalysisRequestHandlerTest, HardAutoCommitTest, CacheHeaderTest, DeleteReplicaTest, TestAnalyzeInfixSuggestions, SuggesterTest, WordBreakSolrSpellCheckerTest, TriLevelCompositeIdRoutingTest, TestStressLucene, TestUtils, TestDefaultSearchFieldResource, PrimitiveFieldTypeTest, TestJettySolrRunner, HdfsCollectionsAPIDistributedZkTest, OpenExchangeRatesOrgProviderTest, RecoveryAfterSoftCommitTest, DistanceUnitsTest, SuggesterTSTTest, TestTrackingShardHandlerFactory, BlockDirectoryTest, TestAddFieldRealTimeGet, TestExpandComponent, TestOrdValues, DistribJoinFromCollectionTest, TestFieldSortValues, TestRestManager, TestPostingsSolrHighlighter, EnumFieldTest, TestSolrConfigHandlerConcurrent, TestIndexingPerformance, TestMergePolicyConfig, TestConfigReload, AnalyticsQueryTest, TestSurroundQueryParser, TestZkChroot, XsltUpdateRequestHandlerTest, TestShortCircuitedRequests, TestHashQParserPlugin, LeaderElectionTest, DistributedQueryElevationComponentTest, HdfsDirectoryFactoryTest, TestQuerySenderNoQuery, ParsingFieldUpdateProcessorsTest, TestManagedSchemaFieldResource, OverseerTest, CoreAdminCreateDiscoverTest, HighlighterConfigTest, DistributedQueryComponentCustomSortTest, TestDistribDocBasedVersion, SpellCheckComponentTest, TestLazyCores, TermVectorComponentDistributedTest, UnloadDistributedZkTest, TestFieldResource, TestPartialUpdateDeduplication, TestClassNameShortening, TestCoreContainer, SolrPluginUtilsTest, TestReversedWildcardFilterFactory, ResponseLogComponentTest, DateRangeFieldTest, AnalysisErrorHandlingTest, OverriddenZkACLAndCredentialsProvidersTest, TestRealTimeGet, CircularListTest, DistributedMLTComponentTest, DistributedTermsComponentTest, TestCollectionAPI, DistributedSpellCheckComponentTest, ConnectionManagerTest, TestTrie, TestSort, ExternalFileFieldSortTest, TestQueryTypes, AlternateDirectoryTest, ExitableDirectoryReaderTest, TestRebalanceLeaders, TestPHPSerializedResponseWriter, TestSortingResponseWriter, TestExactSharedStatsCache, ActionThrottleTest, SyncSliceTest, TestRemoteStreaming, TestCodecSupport, LoggingHandlerTest, SignatureUpdateProcessorFactoryTest, DistributedDebugComponentTest, TestDynamicLoading, LukeRequestHandlerTest, SystemInfoHandlerTest, RAMDirectoryFactoryTest, TestHighlightDedupGrouping, TestUniqueKeyFieldResource, SpatialHeatmapFacetsTest, IndexSchemaRuntimeFieldTest, TestDocumentBuilder, TestHdfsUpdateLog, TestCursorMarkWithoutUniqueKey, StressHdfsTest, AsyncMigrateRouteKeyTest, TestDocBasedVersionConstraints, HdfsDirectoryTest, CachingDirectoryFactoryTest, TestDistributedSearch, TestCSVLoader, TestHashPartitioner, CoreMergeIndexesAdminHandlerTest, CursorMarkTest, CloudExitableDirectoryReaderTest, SliceStateTest, TestBulkSchemaAPI, ExternalCollectionsTest, RecoveryZkTest, TestSchemaNameResource, NotRequiredUniqueKeyTest, TestPseudoReturnFields, SolrXmlInZkTest, TestRandomMergePolicy, TestCloudManagedSchema, ChaosMonkeySafeLeaderTest, TestSerializedLuceneMatchVersion, TestSchemaSimilarityResource, TestSolrConfigHandlerCloud, SolrInfoMBeanTest, NoCacheHeaderTest, BasicDistributedZkTest, OverseerStatusTest, TestExceedMaxTermLength, DateMathParserTest, TestConfigSets, HdfsChaosMonkeySafeLeaderTest, CollectionsAPIAsyncDistributedZkTest, ShardRoutingCustomTest, JsonLoaderTest, TestReplicaProperties, TestRecoveryHdfs, DirectUpdateHandlerTest, TermVectorComponentTest, SolrRequestParserTest, SpellingQueryConverterTest, FileBasedSpellCheckerTest, TestTolerantSearch, RankQueryTest, TestMiniSolrCloudClusterSSL, TestReloadAndDeleteDocs, TestCryptoKeys, TestManagedSchemaDynamicFieldResource, LeaderFailoverAfterPartitionTest, TestGroupingSearch, TestReplicationHandler, ZkControllerTest, TestFunctionQuery, TestCollationField, SearchHandlerTest, TestCollationFieldDocValues, OutputWriterTest, TestBlobHandler, DocumentBuilderTest, AutoCommitTest, SpellCheckCollatorTest, TestReload, TestNonDefinedSimilarityFactory, DistributedExpandComponentTest, SharedFSAutoReplicaFailoverTest, CurrencyFieldXmlFileTest, TestComplexPhraseQParserPlugin, TestQueryUtils, HdfsBasicDistributedZkTest, TestFiltering, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestJoin, StatsComponentTest, TestJmxIntegration, TestRangeQuery, TestQuerySenderListener, TestSolrDeletionPolicy2, CursorPagingTest, TestCopyFieldCollectionResource, TestCoreDiscovery, TestSystemIdResolver, TestFieldCollectionResource, PeerSyncTest, BasicZkTest, TestRecovery, AnalyticsMergeStrategyTest, SuggestComponentTest, ReturnFieldsTest, HdfsSyncSliceTest, TestComponentsName, TestSchemaManager, TestFastWriter, ChaosMonkeyNothingIsSafeTest, TestFastOutputStream, MultiTermTest, DeleteShardTest, SortByFunctionTest, SolrCmdDistributorTest, BadCopyFieldTest, TestLRUStatsCache, TestInitQParser, TestSolrDeletionPolicy1, MBeansHandlerTest, ZkNodePropsTest, SolrIndexConfigTest, MigrateRouteKeyTest, TestBulkSchemaConcurrent, TestXIncludeConfig, TestFieldTypeResource, ReplicationFactorTest]
[junit4] Completed in 70.29s, 1 test, 1 error <<< FAILURES!
[...truncated 795 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958: There were test failures: 473 suites, 1882 tests, 2 errors, 57 ignored (30 assumptions)
Total time: 121 minutes 5 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_31 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any