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 2016/11/21 16:38:17 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_102) - Build #
18339 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18339/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test
Error Message:
Expected 2 of 3 replicas to be active but only found 1; [core_node2:{"core":"c8n_1x3_lf_shard1_replica2","base_url":"http://127.0.0.1:38760/_lu/c","node_name":"127.0.0.1:38760__lu%2Fc","state":"active","leader":"true"}]; clusterState: DocCollection(c8n_1x3_lf//clusterstate.json/30)={ "replicationFactor":"3", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{ "core_node1":{ "state":"down", "base_url":"http://127.0.0.1:37883/_lu/c", "core":"c8n_1x3_lf_shard1_replica3", "node_name":"127.0.0.1:37883__lu%2Fc"}, "core_node2":{ "core":"c8n_1x3_lf_shard1_replica2", "base_url":"http://127.0.0.1:38760/_lu/c", "node_name":"127.0.0.1:38760__lu%2Fc", "state":"active", "leader":"true"}, "core_node3":{ "core":"c8n_1x3_lf_shard1_replica1", "base_url":"http://127.0.0.1:36810/_lu/c", "node_name":"127.0.0.1:36810__lu%2Fc", "state":"down"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false"}
Stack Trace:
java.lang.AssertionError: Expected 2 of 3 replicas to be active but only found 1; [core_node2:{"core":"c8n_1x3_lf_shard1_replica2","base_url":"http://127.0.0.1:38760/_lu/c","node_name":"127.0.0.1:38760__lu%2Fc","state":"active","leader":"true"}]; clusterState: DocCollection(c8n_1x3_lf//clusterstate.json/30)={
"replicationFactor":"3",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node1":{
"state":"down",
"base_url":"http://127.0.0.1:37883/_lu/c",
"core":"c8n_1x3_lf_shard1_replica3",
"node_name":"127.0.0.1:37883__lu%2Fc"},
"core_node2":{
"core":"c8n_1x3_lf_shard1_replica2",
"base_url":"http://127.0.0.1:38760/_lu/c",
"node_name":"127.0.0.1:38760__lu%2Fc",
"state":"active",
"leader":"true"},
"core_node3":{
"core":"c8n_1x3_lf_shard1_replica1",
"base_url":"http://127.0.0.1:36810/_lu/c",
"node_name":"127.0.0.1:36810__lu%2Fc",
"state":"down"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false"}
at __randomizedtesting.SeedInfo.seed([20C1B8A593B9DEFB:A895877F3D45B303]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.testRf3WithLeaderFailover(LeaderFailoverAfterPartitionTest.java:170)
at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFailoverAfterPartitionTest.java:57)
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:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.core.TestDynamicLoading.testDynamicLoading
Error Message:
Could not get expected value 'X val' for path 'x' full output: { "responseHeader":{ "status":0, "QTime":0}, "params":{"wt":"json"}, "context":{ "webapp":"", "path":"/test1", "httpMethod":"GET"}, "class":"org.apache.solr.core.BlobStoreTestRequestHandler", "x":null}, from server: null
Stack Trace:
java.lang.AssertionError: Could not get expected value 'X val' for path 'x' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"params":{"wt":"json"},
"context":{
"webapp":"",
"path":"/test1",
"httpMethod":"GET"},
"class":"org.apache.solr.core.BlobStoreTestRequestHandler",
"x":null}, from server: null
at __randomizedtesting.SeedInfo.seed([20C1B8A593B9DEFB:F88C95F264647B5B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:535)
at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:232)
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:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.schema.PreAnalyzedFieldManagedSchemaCloudTest.testAdd2Fields
Error Message:
No live SolrServers available to handle this request:[http://127.0.0.1:43464/solr/managed-preanalyzed, http://127.0.0.1:41997/solr/managed-preanalyzed]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:43464/solr/managed-preanalyzed, http://127.0.0.1:41997/solr/managed-preanalyzed]
at __randomizedtesting.SeedInfo.seed([20C1B8A593B9DEFB:88D40A4E4FBF4D0D]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:418)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1288)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1058)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1000)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
at org.apache.solr.schema.PreAnalyzedFieldManagedSchemaCloudTest.addField(PreAnalyzedFieldManagedSchemaCloudTest.java:61)
at org.apache.solr.schema.PreAnalyzedFieldManagedSchemaCloudTest.testAdd2Fields(PreAnalyzedFieldManagedSchemaCloudTest.java:52)
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:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:43464/solr/managed-preanalyzed: No such path /schema/fields/field2
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:439)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:391)
... 45 more
Build Log:
[...truncated 10750 lines...]
[junit4] Suite: org.apache.solr.schema.PreAnalyzedFieldManagedSchemaCloudTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/init-core-data-001
[junit4] 2> 73766 INFO (SUITE-PreAnalyzedFieldManagedSchemaCloudTest-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 73766 INFO (SUITE-PreAnalyzedFieldManagedSchemaCloudTest-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001
[junit4] 2> 73766 INFO (SUITE-PreAnalyzedFieldManagedSchemaCloudTest-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 73766 INFO (Thread-168) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 73766 INFO (Thread-168) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 73866 INFO (SUITE-PreAnalyzedFieldManagedSchemaCloudTest-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:38099
[junit4] 2> 73875 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 73875 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 73876 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14becfee{/solr,null,AVAILABLE}
[junit4] 2> 73876 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@40d680c6{/solr,null,AVAILABLE}
[junit4] 2> 73877 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@76e80580{HTTP/1.1,[http/1.1]}{127.0.0.1:43464}
[junit4] 2> 73877 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@50d8db24{HTTP/1.1,[http/1.1]}{127.0.0.1:41997}
[junit4] 2> 73877 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.Server Started @75747ms
[junit4] 2> 73877 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.Server Started @75747ms
[junit4] 2> 73877 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43464}
[junit4] 2> 73877 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41997}
[junit4] 2> 73878 ERROR (jetty-launcher-83-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 73878 ERROR (jetty-launcher-83-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 73878 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 73878 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 73878 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 73878 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 73878 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 73878 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 73878 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-21T15:53:57.433Z
[junit4] 2> 73878 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-21T15:53:57.433Z
[junit4] 2> 73880 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 73880 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 73886 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x1588798074a0002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 73888 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38099/solr
[junit4] 2> 73888 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38099/solr
[junit4] 2> 73901 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:41997_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41997_solr
[junit4] 2> 73901 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:43464_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43464_solr
[junit4] 2> 73902 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:41997_solr ] o.a.s.c.Overseer Overseer (id=96976479015534597-127.0.0.1:41997_solr-n_0000000000) starting
[junit4] 2> 73902 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 73902 INFO (zkCallback-93-thread-1-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 73907 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:41997_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41997_solr
[junit4] 2> 73907 INFO (zkCallback-93-thread-1-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 73907 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 73964 INFO (jetty-launcher-83-thread-2) [n:127.0.0.1:41997_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node2/.
[junit4] 2> 73994 INFO (jetty-launcher-83-thread-1) [n:127.0.0.1:43464_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node1/.
[junit4] 2> 74000 INFO (SUITE-PreAnalyzedFieldManagedSchemaCloudTest-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 74004 INFO (qtp1992514145-580) [n:127.0.0.1:43464_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=cloud-managed-preanalyzed&maxShardsPerNode=1&name=managed-preanalyzed&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 74006 INFO (OverseerThreadFactory-301-thread-1-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.c.CreateCollectionCmd Create collection managed-preanalyzed
[junit4] 2> 74110 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=cloud-managed-preanalyzed&newCollection=true&name=managed-preanalyzed_shard2_replica1&action=CREATE&numShards=2&collection=managed-preanalyzed&shard=shard2&wt=javabin&version=2
[junit4] 2> 74110 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=cloud-managed-preanalyzed&newCollection=true&name=managed-preanalyzed_shard1_replica1&action=CREATE&numShards=2&collection=managed-preanalyzed&shard=shard1&wt=javabin&version=2
[junit4] 2> 74214 INFO (zkCallback-93-thread-1-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed-preanalyzed/state.json] for collection [managed-preanalyzed] has occurred - updating... (live nodes size: [2])
[junit4] 2> 74214 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed-preanalyzed/state.json] for collection [managed-preanalyzed] has occurred - updating... (live nodes size: [2])
[junit4] 2> 75118 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 75119 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 75139 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 x:managed-preanalyzed_shard2_replica1] o.a.s.s.IndexSchema [managed-preanalyzed_shard2_replica1] Schema name=managed-preanalyzed
[junit4] 2> 75139 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 x:managed-preanalyzed_shard1_replica1] o.a.s.s.IndexSchema [managed-preanalyzed_shard1_replica1] Schema name=managed-preanalyzed
[junit4] 2> 75151 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 x:managed-preanalyzed_shard2_replica1] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 75151 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 x:managed-preanalyzed_shard1_replica1] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 75152 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 x:managed-preanalyzed_shard2_replica1] o.a.s.c.CoreContainer Creating SolrCore 'managed-preanalyzed_shard2_replica1' using configuration from collection managed-preanalyzed
[junit4] 2> 75152 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 x:managed-preanalyzed_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'managed-preanalyzed_shard1_replica1' using configuration from collection managed-preanalyzed
[junit4] 2> 75152 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SolrCore [[managed-preanalyzed_shard2_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node1/managed-preanalyzed_shard2_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node1/./managed-preanalyzed_shard2_replica1/data/]
[junit4] 2> 75152 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SolrCore [[managed-preanalyzed_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node2/managed-preanalyzed_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node2/./managed-preanalyzed_shard1_replica1/data/]
[junit4] 2> 75159 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 75159 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 75160 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 75160 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 75160 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b2c66cd[managed-preanalyzed_shard2_replica1] main]
[junit4] 2> 75161 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 75161 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 75161 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed-preanalyzed
[junit4] 2> 75161 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed-preanalyzed
[junit4] 2> 75162 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 75162 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 75163 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b25049d[managed-preanalyzed_shard1_replica1] main]
[junit4] 2> 75163 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> 75163 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed-preanalyzed
[junit4] 2> 75164 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed-preanalyzed
[junit4] 2> 75164 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> 75164 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 75164 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 75164 INFO (searcherExecutor-306-thread-1-processing-n:127.0.0.1:43464_solr x:managed-preanalyzed_shard2_replica1 s:shard2 c:managed-preanalyzed r:core_node1) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard2_replica1] Registered new searcher Searcher@1b2c66cd[managed-preanalyzed_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 75164 INFO (searcherExecutor-307-thread-1-processing-n:127.0.0.1:41997_solr x:managed-preanalyzed_shard1_replica1 s:shard1 c:managed-preanalyzed r:core_node2) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard1_replica1] Registered new searcher Searcher@4b25049d[managed-preanalyzed_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 75164 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551623665713414144
[junit4] 2> 75164 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551623665713414144
[junit4] 2> 75170 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 75170 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 75170 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43464/solr/managed-preanalyzed_shard2_replica1/
[junit4] 2> 75170 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 75170 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SyncStrategy http://127.0.0.1:43464/solr/managed-preanalyzed_shard2_replica1/ has no replicas
[junit4] 2> 75171 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 75171 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 75171 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:41997/solr/managed-preanalyzed_shard1_replica1/
[junit4] 2> 75171 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 75171 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SyncStrategy http://127.0.0.1:41997/solr/managed-preanalyzed_shard1_replica1/ has no replicas
[junit4] 2> 75174 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43464/solr/managed-preanalyzed_shard2_replica1/ shard2
[junit4] 2> 75174 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:41997/solr/managed-preanalyzed_shard1_replica1/ shard1
[junit4] 2> 75277 INFO (zkCallback-93-thread-1-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed-preanalyzed/state.json] for collection [managed-preanalyzed] has occurred - updating... (live nodes size: [2])
[junit4] 2> 75277 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed-preanalyzed/state.json] for collection [managed-preanalyzed] has occurred - updating... (live nodes size: [2])
[junit4] 2> 75325 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 75325 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 75327 INFO (qtp1992514145-582) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=cloud-managed-preanalyzed&newCollection=true&name=managed-preanalyzed_shard2_replica1&action=CREATE&numShards=2&collection=managed-preanalyzed&shard=shard2&wt=javabin&version=2} status=0 QTime=1216
[junit4] 2> 75327 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=cloud-managed-preanalyzed&newCollection=true&name=managed-preanalyzed_shard1_replica1&action=CREATE&numShards=2&collection=managed-preanalyzed&shard=shard1&wt=javabin&version=2} status=0 QTime=1216
[junit4] 2> 75329 INFO (qtp1992514145-580) [n:127.0.0.1:43464_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 75429 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed-preanalyzed/state.json] for collection [managed-preanalyzed] has occurred - updating... (live nodes size: [2])
[junit4] 2> 75429 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed-preanalyzed/state.json] for collection [managed-preanalyzed] has occurred - updating... (live nodes size: [2])
[junit4] 2> 76007 INFO (OverseerCollectionConfigSetProcessor-96976479015534597-127.0.0.1:41997_solr-n_0000000000) [n:127.0.0.1:41997_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 76329 INFO (qtp1992514145-580) [n:127.0.0.1:43464_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=cloud-managed-preanalyzed&maxShardsPerNode=1&name=managed-preanalyzed&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2324
[junit4] 2> 76338 INFO (TEST-PreAnalyzedFieldManagedSchemaCloudTest.testAdd2Fields-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAdd2Fields
[junit4] 2> 76349 INFO (qtp1054918052-585) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.SchemaManager managed schema loaded . version : 0
[junit4] 2> 76351 INFO (qtp1054918052-585) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.IndexSchema [managed-preanalyzed_shard1_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76356 INFO (qtp1054918052-585) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76371 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed-preanalyzed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 76371 INFO (qtp1054918052-585) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.ZkController Persisted config data to node /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> 76371 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed-preanalyzed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 76371 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 76372 INFO (qtp1054918052-585) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply schema update version 1 for collection managed-preanalyzed
[junit4] 2> 76372 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.c.SolrCore config update listener called for core managed-preanalyzed_shard1_replica1
[junit4] 2> 76372 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.c.SolrCore config update listener called for core managed-preanalyzed_shard2_replica1
[junit4] 2> 76373 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.s.SchemaManager managed schema loaded . version : 1
[junit4] 2> 76373 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.s.SchemaManager managed schema loaded . version : 1
[junit4] 2> 76375 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard2_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76376 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard1_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76376 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard2_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76380 INFO (qtp1992514145-574) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=1, currentVersion=0) before returning version!
[junit4] 2> 76389 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76389 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76389 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.c.SolrCore core reload managed-preanalyzed_shard2_replica1
[junit4] 2> 76389 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.c.SolrCore core reload managed-preanalyzed_shard1_replica1
[junit4] 2> 76389 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76389 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 18 ms
[junit4] 2> 76389 INFO (qtp1992514145-574) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.S.Request [managed-preanalyzed_shard2_replica1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 76395 INFO (qtp1054918052-585) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.ManagedIndexSchema Took 23.0ms for 1 replicas to apply schema update version 1 for collection managed-preanalyzed
[junit4] 2> 76395 INFO (qtp1054918052-585) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.S.Request [managed-preanalyzed_shard1_replica1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=48
[junit4] 2> 76396 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 76398 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard1_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76400 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 76400 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 76406 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76406 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 10 ms
[junit4] 2> 76409 INFO (qtp1054918052-571) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.S.Request [managed-preanalyzed_shard1_replica1] webapp=/solr path=/schema/fields/field1 params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 76411 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard1_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76411 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard2_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76413 INFO (qtp1992514145-584) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.SchemaManager managed schema loaded . version : 1
[junit4] 2> 76433 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76433 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76433 INFO (qtp1992514145-584) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.IndexSchema [managed-preanalyzed_shard2_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76434 INFO (Thread-188) [n:127.0.0.1:43464_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'managed-preanalyzed_shard2_replica1' using configuration from collection managed-preanalyzed
[junit4] 2> 76434 INFO (Thread-187) [n:127.0.0.1:41997_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'managed-preanalyzed_shard1_replica1' using configuration from collection managed-preanalyzed
[junit4] 2> 76435 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SolrCore [[managed-preanalyzed_shard2_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node1/managed-preanalyzed_shard2_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node1/./managed-preanalyzed_shard2_replica1/data/]
[junit4] 2> 76435 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SolrCore [[managed-preanalyzed_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node2/managed-preanalyzed_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001/tempDir-001/node2/./managed-preanalyzed_shard1_replica1/data/]
[junit4] 2> 76440 INFO (qtp1992514145-584) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76441 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 76441 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 76441 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed-preanalyzed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 76441 INFO (qtp1992514145-584) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.ZkController Persisted config data to node /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> 76442 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed-preanalyzed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 76442 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 2 from ZooKeeper
[junit4] 2> 76442 INFO (qtp1992514145-584) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply schema update version 2 for collection managed-preanalyzed
[junit4] 2> 76445 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 76445 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 76446 INFO (Thread-189) [n:127.0.0.1:41997_solr ] o.a.s.c.SolrCore config update listener called for core managed-preanalyzed_shard1_replica1
[junit4] 2> 76446 INFO (Thread-190) [n:127.0.0.1:43464_solr ] o.a.s.c.SolrCore config update listener called for core managed-preanalyzed_shard2_replica1
[junit4] 2> 76446 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@51e93075[managed-preanalyzed_shard2_replica1] main]
[junit4] 2> 76446 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a014e23[managed-preanalyzed_shard1_replica1] main]
[junit4] 2> 76447 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed-preanalyzed
[junit4] 2> 76447 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed-preanalyzed
[junit4] 2> 76448 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed-preanalyzed
[junit4] 2> 76448 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> 76448 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed-preanalyzed
[junit4] 2> 76448 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> 76448 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 76448 INFO (Thread-190) [n:127.0.0.1:43464_solr ] o.a.s.s.SchemaManager managed schema loaded . version : 2
[junit4] 2> 76448 INFO (Thread-189) [n:127.0.0.1:41997_solr ] o.a.s.s.SchemaManager managed schema loaded . version : 2
[junit4] 2> 76448 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 76448 INFO (searcherExecutor-317-thread-1-processing-n:127.0.0.1:43464_solr x:managed-preanalyzed_shard2_replica1 s:shard2 c:managed-preanalyzed r:core_node1) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard2_replica1] Registered new searcher Searcher@51e93075[managed-preanalyzed_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 76448 INFO (qtp1054918052-575) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.h.SchemaHandler REFRESHING SCHEMA (refreshIfBelowVersion=2, currentVersion=1) before returning version!
[junit4] 2> 76451 INFO (searcherExecutor-318-thread-1-processing-n:127.0.0.1:41997_solr x:managed-preanalyzed_shard1_replica1 s:shard1 c:managed-preanalyzed r:core_node2) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard1_replica1] Registered new searcher Searcher@5a014e23[managed-preanalyzed_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 76451 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551623667062931456
[junit4] 2> 76451 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1551623667062931456
[junit4] 2> 76452 INFO (Thread-190) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard2_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76452 INFO (Thread-189) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard1_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76452 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard1_replica1] Schema name=managed-preanalyzed
[junit4] 2> 76455 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 76456 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 76456 INFO (Thread-188) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d3f3161[managed-preanalyzed_shard2_replica1] main]
[junit4] 2> 76456 INFO (Thread-187) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a4a743c[managed-preanalyzed_shard1_replica1] main]
[junit4] 2> 76456 INFO (searcherExecutor-317-thread-1-processing-n:127.0.0.1:43464_solr x:managed-preanalyzed_shard2_replica1 s:shard2 c:managed-preanalyzed r:core_node1) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard2_replica1] Registered new searcher Searcher@2d3f3161[managed-preanalyzed_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 76456 INFO (searcherExecutor-318-thread-1-processing-n:127.0.0.1:41997_solr x:managed-preanalyzed_shard1_replica1 s:shard1 c:managed-preanalyzed r:core_node2) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard1_replica1] Registered new searcher Searcher@3a4a743c[managed-preanalyzed_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 76460 INFO (Thread-190) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76460 INFO (Thread-190) [n:127.0.0.1:43464_solr ] o.a.s.c.SolrCore core reload managed-preanalyzed_shard2_replica1
[junit4] 2> 76460 INFO (Thread-189) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76460 INFO (Thread-189) [n:127.0.0.1:41997_solr ] o.a.s.c.SolrCore core reload managed-preanalyzed_shard1_replica1
[junit4] 2> 76460 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76460 INFO (zkCallback-93-thread-2-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 15 ms
[junit4] 2> 76460 INFO (qtp1054918052-575) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.S.Request [managed-preanalyzed_shard1_replica1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 76460 INFO (qtp1054918052-575) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@5665b63c
[junit4] 2> 76463 INFO (qtp1992514145-584) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.s.ManagedIndexSchema Took 20.0ms for 1 replicas to apply schema update version 2 for collection managed-preanalyzed
[junit4] 2> 76463 INFO (qtp1992514145-584) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.S.Request [managed-preanalyzed_shard2_replica1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=50
[junit4] 2> 76463 INFO (qtp1992514145-584) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard2_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@5ca13a69
[junit4] 2> 76463 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 2 from ZooKeeper
[junit4] 2> 76464 INFO (qtp1054918052-581) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.S.Request [managed-preanalyzed_shard1_replica1] webapp=/solr path=/schema/fields/field2 params={wt=javabin&version=2} status=404 QTime=0
[junit4] 2> 76464 INFO (qtp1992514145-572) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.S.Request [managed-preanalyzed_shard2_replica1] webapp=/solr path=/schema/fields/field2 params={wt=javabin&version=2} status=404 QTime=0
[junit4] 2> 76465 ERROR (TEST-PreAnalyzedFieldManagedSchemaCloudTest.testAdd2Fields-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.s.i.CloudSolrClient Request to collection managed-preanalyzed failed due to (404) org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:43464/solr/managed-preanalyzed: No such path /schema/fields/field2, retry? 0
[junit4] 2> 76465 INFO (TEST-PreAnalyzedFieldManagedSchemaCloudTest.testAdd2Fields-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAdd2Fields
[junit4] 2> 76466 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema [managed-preanalyzed_shard2_replica1] Schema name=managed-preanalyzed
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=PreAnalyzedFieldManagedSchemaCloudTest -Dtests.method=testAdd2Fields -Dtests.seed=20C1B8A593B9DEFB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=th -Dtests.timezone=America/Buenos_Aires -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.14s J0 | PreAnalyzedFieldManagedSchemaCloudTest.testAdd2Fields <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:43464/solr/managed-preanalyzed, http://127.0.0.1:41997/solr/managed-preanalyzed]
[junit4] > at __randomizedtesting.SeedInfo.seed([20C1B8A593B9DEFB:88D40A4E4FBF4D0D]:0)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:418)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1288)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1058)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1000)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
[junit4] > at org.apache.solr.schema.PreAnalyzedFieldManagedSchemaCloudTest.addField(PreAnalyzedFieldManagedSchemaCloudTest.java:61)
[junit4] > at org.apache.solr.schema.PreAnalyzedFieldManagedSchemaCloudTest.testAdd2Fields(PreAnalyzedFieldManagedSchemaCloudTest.java:52)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:43464/solr/managed-preanalyzed: No such path /schema/fields/field2
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:439)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:391)
[junit4] > ... 45 more
[junit4] 2> 76478 INFO (Thread-190) [n:127.0.0.1:43464_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 76478 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@76e80580{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 76478 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@50d8db24{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 76478 INFO (jetty-launcher-83-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1238561074
[junit4] 2> 76478 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=400375012
[junit4] 2> 76480 INFO (coreCloseExecutor-326-thread-1) [n:127.0.0.1:43464_solr c:managed-preanalyzed s:shard2 r:core_node1 x:managed-preanalyzed_shard2_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard2_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@495e1882
[junit4] 2> 76480 INFO (coreCloseExecutor-327-thread-1) [n:127.0.0.1:41997_solr c:managed-preanalyzed s:shard1 r:core_node2 x:managed-preanalyzed_shard1_replica1] o.a.s.c.SolrCore [managed-preanalyzed_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@47e823b3
[junit4] 2> 76481 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.IndexSchema Loaded schema managed-preanalyzed/1.6 with uniqueid field id
[junit4] 2> 76481 INFO (zkCallback-94-thread-1-processing-n:127.0.0.1:43464_solr) [n:127.0.0.1:43464_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 17 ms
[junit4] 2> 76481 INFO (Thread-189) [n:127.0.0.1:41997_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 76484 INFO (jetty-launcher-83-thread-1) [ ] o.a.s.c.Overseer Overseer (id=96976479015534597-127.0.0.1:41997_solr-n_0000000000) closing
[junit4] 2> 76484 INFO (OverseerStateUpdate-96976479015534597-127.0.0.1:41997_solr-n_0000000000) [n:127.0.0.1:41997_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41997_solr
[junit4] 2> 76484 INFO (zkCallback-93-thread-1-processing-n:127.0.0.1:41997_solr) [n:127.0.0.1:41997_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 76485 INFO (jetty-launcher-83-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@14becfee{/solr,null,UNAVAILABLE}
[junit4] 2> 76485 INFO (jetty-launcher-83-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40d680c6{/solr,null,UNAVAILABLE}
[junit4] 2> 76487 INFO (SUITE-PreAnalyzedFieldManagedSchemaCloudTest-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38099 38099
[junit4] 2> 76488 ERROR (Thread-190) [n:127.0.0.1:43464_solr ] o.a.s.s.ManagedIndexSchemaFactory Error attempting to access /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:356)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:126)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:45)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:75)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:107)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:78)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:968)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2732)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$4(ZkController.java:2448)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 76489 ERROR (Thread-189) [n:127.0.0.1:41997_solr ] o.a.s.s.ManagedIndexSchemaFactory Error attempting to access /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:356)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:126)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:45)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:75)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:107)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:78)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:968)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2732)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$4(ZkController.java:2448)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 76489 WARN (Thread-190) [n:127.0.0.1:43464_solr ] o.a.s.c.ZkController listener throws error
[junit4] 2> org.apache.solr.common.SolrException: Unable to reload core [managed-preanalyzed_shard2_replica1]
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:976)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2732)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$4(ZkController.java:2448)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not load conf for core managed-preanalyzed_shard2_replica1: Error attempting to access /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:85)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:968)
[junit4] 2> ... 3 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error attempting to access /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:141)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:45)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:75)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:107)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:78)
[junit4] 2> ... 4 more
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:356)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:126)
[junit4] 2> ... 8 more
[junit4] 2> 76490 WARN (Thread-189) [n:127.0.0.1:41997_solr ] o.a.s.c.ZkController listener throws error
[junit4] 2> org.apache.solr.common.SolrException: Unable to reload core [managed-preanalyzed_shard1_replica1]
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:976)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2732)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$4(ZkController.java:2448)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not load conf for core managed-preanalyzed_shard1_replica1: Error attempting to access /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:85)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:968)
[junit4] 2> ... 3 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error attempting to access /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:141)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:45)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:75)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:107)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:78)
[junit4] 2> ... 4 more
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:356)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:126)
[junit4] 2> ... 8 more
[junit4] 2> 81523 INFO (Thread-168) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38099 38099
[junit4] 2> 81523 WARN (Thread-168) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/configs/cloud-managed-preanalyzed
[junit4] 2> 4 /solr/configs/cloud-managed-preanalyzed/managed-schema
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/collections/managed-preanalyzed/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/overseer/queue
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 81523 INFO (SUITE-PreAnalyzedFieldManagedSchemaCloudTest-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.schema.PreAnalyzedFieldManagedSchemaCloudTest_20C1B8A593B9DEFB-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=384, maxMBSortInHeap=7.407271947702419, sim=RandomSimilarity(queryNorm=true): {}, locale=th, timezone=America/Buenos_Aires
[junit4] 2> NOTE: Linux 4.4.0-47-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=12,threads=1,free=321974224,total=508887040
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrCoreProperties, TestUniqueKeyFieldResource, FullHLLTest, CloudMLTQParserTest, TestFileDictionaryLookup, TestValueSourceCache, LeaderElectionContextKeyTest, CoreAdminRequestStatusTest, TestDistributedSearch, TestConfigSetsAPIExclusivity, TestCollapseQParserPlugin, TestFunctionQuery, PreAnalyzedFieldManagedSchemaCloudTest]
[junit4] Completed [40/652 (1!)] on J0 in 7.77s, 1 test, 1 error <<< FAILURES!
[...truncated 70 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_20C1B8A593B9DEFB-001/init-core-data-001
[junit4] 2> 163814 INFO (SUITE-TestDynamicLoading-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 163815 INFO (SUITE-TestDynamicLoading-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 163816 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 163817 INFO (Thread-309) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 163817 INFO (Thread-309) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 163917 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.ZkTestServer start zk server on port:45147
[junit4] 2> 163923 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 163924 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 163925 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 163925 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 163926 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 163926 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 163927 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 163927 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 163928 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 163928 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 163929 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 163988 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_20C1B8A593B9DEFB-001/control-001/cores/collection1
[junit4] 2> 163990 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 163991 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@99d42b3{/,null,AVAILABLE}
[junit4] 2> 163993 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@414386fe{SSL,[ssl, http/1.1]}{127.0.0.1:38630}
[junit4] 2> 163993 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.e.j.s.Server Started @165863ms
[junit4] 2> 163993 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_20C1B8A593B9DEFB-001/tempDir-001/control/data, hostContext=/, hostPort=38630, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_20C1B8A593B9DEFB-001/control-001/cores}
[junit4] 2> 163993 ERROR (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 163993 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 163994 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 163994 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 163994 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-11-21T15:55:27.549Z
[junit4] 2> 163996 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[20C1B8A593B9DEFB]) [ ] o.
[...truncated too long message...]
a:348)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:268)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 230020 INFO (recoveryExecutor-203-thread-1-processing-n:127.0.0.1:37883__lu%2Fc x:c8n_1x3_lf_shard1_replica3 s:shard1 c:c8n_1x3_lf r:core_node1) [n:127.0.0.1:37883__lu%2Fc c:c8n_1x3_lf s:shard1 r:core_node1 x:c8n_1x3_lf_shard1_replica3] o.a.s.c.RecoveryStrategy RecoveryStrategy has been closed
[junit4] 2> 230020 INFO (recoveryExecutor-203-thread-1-processing-n:127.0.0.1:37883__lu%2Fc x:c8n_1x3_lf_shard1_replica3 s:shard1 c:c8n_1x3_lf r:core_node1) [n:127.0.0.1:37883__lu%2Fc c:c8n_1x3_lf s:shard1 r:core_node1 x:c8n_1x3_lf_shard1_replica3] o.a.s.c.RecoveryStrategy Finished recovery process, successful=[false]
[junit4] 2> 230020 INFO (recoveryExecutor-203-thread-1-processing-n:127.0.0.1:37883__lu%2Fc x:c8n_1x3_lf_shard1_replica3 s:shard1 c:c8n_1x3_lf r:core_node1) [n:127.0.0.1:37883__lu%2Fc c:c8n_1x3_lf s:shard1 r:core_node1 x:c8n_1x3_lf_shard1_replica3] o.a.s.c.SolrCore [c8n_1x3_lf_shard1_replica3] CLOSING SolrCore org.apache.solr.core.SolrCore@4173c2cd
[junit4] 2> 230021 WARN (recoveryExecutor-203-thread-1-processing-n:127.0.0.1:37883__lu%2Fc x:c8n_1x3_lf_shard1_replica3 s:shard1 c:c8n_1x3_lf r:core_node1) [n:127.0.0.1:37883__lu%2Fc c:c8n_1x3_lf s:shard1 r:core_node1 x:c8n_1x3_lf_shard1_replica3] o.a.s.c.RecoveryStrategy Stopping recovery for core=[c8n_1x3_lf_shard1_replica3] coreNodeName=[core_node1]
[junit4] 2> 230031 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.Overseer Overseer (id=96976481651785742-127.0.0.1:37883__lu%2Fc-n_0000000003) closing
[junit4] 2> 230031 INFO (OverseerStateUpdate-96976481651785742-127.0.0.1:37883__lu%2Fc-n_0000000003) [n:127.0.0.1:37883__lu%2Fc ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37883__lu%2Fc
[junit4] 2> 230032 WARN (zkCallback-205-thread-6-processing-n:127.0.0.1:37883__lu%2Fc) [n:127.0.0.1:37883__lu%2Fc ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 230032 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[20C1B8A593B9DEFB]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@329a149b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 230033 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[20C1B8A593B9DEFB]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@63449c0a{/_lu/c,null,UNAVAILABLE}
[junit4] 2> 230033 INFO (TEST-LeaderFailoverAfterPartitionTest.test-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43191 43191
[junit4] 2> 230061 INFO (Thread-234) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43191 43191
[junit4] 2> 230061 WARN (Thread-234) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3 /solr/collections/c8n_1x3_lf/state.json
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 2 /solr/overseer_elect/election/96976481651785736-127.0.0.1:38760__lu%2Fc-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 115 /solr/overseer/collection-queue-work
[junit4] 2> 41 /solr/overseer/queue
[junit4] 2> 10 /solr/overseer/queue-work
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 230061 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.SocketProxy Closing 1 connections to: http://127.0.0.1:37883/_lu/c, target: http://127.0.0.1:42920/_lu/c
[junit4] 2> 230061 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.SocketProxy Closing 13 connections to: http://127.0.0.1:38760/_lu/c, target: http://127.0.0.1:35546/_lu/c
[junit4] 2> 230061 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.SocketProxy Closing 1 connections to: http://127.0.0.1:37067/_lu/c, target: http://127.0.0.1:38598/_lu/c
[junit4] 2> 230061 WARN (TEST-LeaderFailoverAfterPartitionTest.test-seed#[20C1B8A593B9DEFB]) [ ] o.a.s.c.SocketProxy Closing 0 connections to: http://127.0.0.1:36810/_lu/c, target: http://127.0.0.1:42687/_lu/c
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=LeaderFailoverAfterPartitionTest -Dtests.method=test -Dtests.seed=20C1B8A593B9DEFB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ca -Dtests.timezone=Africa/Ndjamena -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 116s J1 | LeaderFailoverAfterPartitionTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected 2 of 3 replicas to be active but only found 1; [core_node2:{"core":"c8n_1x3_lf_shard1_replica2","base_url":"http://127.0.0.1:38760/_lu/c","node_name":"127.0.0.1:38760__lu%2Fc","state":"active","leader":"true"}]; clusterState: DocCollection(c8n_1x3_lf//clusterstate.json/30)={
[junit4] > "replicationFactor":"3",
[junit4] > "shards":{"shard1":{
[junit4] > "range":"80000000-7fffffff",
[junit4] > "state":"active",
[junit4] > "replicas":{
[junit4] > "core_node1":{
[junit4] > "state":"down",
[junit4] > "base_url":"http://127.0.0.1:37883/_lu/c",
[junit4] > "core":"c8n_1x3_lf_shard1_replica3",
[junit4] > "node_name":"127.0.0.1:37883__lu%2Fc"},
[junit4] > "core_node2":{
[junit4] > "core":"c8n_1x3_lf_shard1_replica2",
[junit4] > "base_url":"http://127.0.0.1:38760/_lu/c",
[junit4] > "node_name":"127.0.0.1:38760__lu%2Fc",
[junit4] > "state":"active",
[junit4] > "leader":"true"},
[junit4] > "core_node3":{
[junit4] > "core":"c8n_1x3_lf_shard1_replica1",
[junit4] > "base_url":"http://127.0.0.1:36810/_lu/c",
[junit4] > "node_name":"127.0.0.1:36810__lu%2Fc",
[junit4] > "state":"down"}}}},
[junit4] > "router":{"name":"compositeId"},
[junit4] > "maxShardsPerNode":"1",
[junit4] > "autoAddReplicas":"false"}
[junit4] > at __randomizedtesting.SeedInfo.seed([20C1B8A593B9DEFB:A895877F3D45B303]:0)
[junit4] > at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.testRf3WithLeaderFailover(LeaderFailoverAfterPartitionTest.java:170)
[junit4] > at org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFailoverAfterPartitionTest.java:57)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 230065 INFO (SUITE-LeaderFailoverAfterPartitionTest-seed#[20C1B8A593B9DEFB]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_20C1B8A593B9DEFB-001
[junit4] 2> Nov 21, 2016 3:56:33 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {range_facet_l_dv=Lucene50(blocksize=128), _version_=PostingsFormat(name=LuceneFixedGap), multiDefault=BlockTreeOrds(blocksize=128), a_t=PostingsFormat(name=LuceneFixedGap), intDefault=PostingsFormat(name=LuceneFixedGap), id=Lucene50(blocksize=128), range_facet_i_dv=PostingsFormat(name=LuceneFixedGap), text=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l=PostingsFormat(name=LuceneFixedGap), timestamp=PostingsFormat(name=LuceneFixedGap)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=507, maxMBSortInHeap=5.580433923121594, sim=RandomSimilarity(queryNorm=false): {}, locale=ca, timezone=Africa/Ndjamena
[junit4] 2> NOTE: Linux 4.4.0-47-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=12,threads=1,free=271508416,total=508887040
[junit4] 2> NOTE: All tests run in this JVM: [ShardRoutingCustomTest, VersionInfoTest, ShardRoutingTest, IndexSchemaTest, SpellCheckCollatorTest, QueryParsingTest, TestUninvertingReader, TestFieldTypeResource, TestQueryTypes, ZkCLITest, TestDocumentBuilder, DistributedIntervalFacetingTest, TestDefaultSearchFieldResource, BlockJoinFacetSimpleTest, LeaderFailoverAfterPartitionTest]
[junit4] Completed [68/652 (3!)] on J1 in 116.70s, 1 test, 1 failure <<< FAILURES!
[...truncated 64217 lines...]
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+140) - Build
# 18340 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18340/
Java: 32bit/jdk-9-ea+140 -client -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.handler.component.SpellCheckComponentTest.testThresholdTokenFrequency
Error Message:
Path not found: /spellcheck/suggestions/[1]/suggestion
Stack Trace:
java.lang.RuntimeException: Path not found: /spellcheck/suggestions/[1]/suggestion
at __randomizedtesting.SeedInfo.seed([8B58D135477A65A:821202E2DB9C9F21]:0)
at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:900)
at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:847)
at org.apache.solr.handler.component.SpellCheckComponentTest.testThresholdTokenFrequency(SpellCheckComponentTest.java:277)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
Build Log:
[...truncated 11579 lines...]
[junit4] Suite: org.apache.solr.handler.component.SpellCheckComponentTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_8B58D135477A65A-001/init-core-data-001
[junit4] 2> 1136924 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1136925 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1136926 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1137016 WARN (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1137016 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1137028 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1137102 WARN (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1137103 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1137135 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1137214 WARN (coreLoadExecutor-4049-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1137215 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1137231 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1137298 WARN (coreLoadExecutor-4049-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1137299 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1137322 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1137323 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_8B58D135477A65A-001/init-core-data-001/]
[junit4] 2> 1137323 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.702608438448745]
[junit4] 2> 1137328 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1137328 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1137328 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=34.5361328125, floorSegmentMB=0.5771484375, forceMergeDeletesPctAllowed=0.694916593013406, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6824311867608911
[junit4] 2> 1137328 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9f881f[collection1] main]
[junit4] 2> 1137328 WARN (coreLoadExecutor-4049-thread-1) [ ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1137329 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1137388 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default
[junit4] 2> 1137438 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default_teststop
[junit4] 2> 1137438 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=teststop}
[junit4] 2> 1137438 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct_lowerfilt,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=lowerfilt}
[junit4] 2> 1137508 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold
[junit4] 2> 1137508 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=threshold_direct,classname=solr.DirectSolrSpellChecker,field=lowerfilt,spellcheckIndexDir=spellcheckerThreshold,buildOnCommit=true,thresholdTokenFrequency=0.29}
[junit4] 2> 1137508 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold_direct
[junit4] 2> 1137554 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: multipleFields
[junit4] 2> 1137754 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: freq
[junit4] 2> 1137825 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: fqcn
[junit4] 2> 1137826 INFO (coreLoadExecutor-4049-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1137826 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1137827 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1137827 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
[junit4] 2> 1137829 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testThresholdTokenFrequency
[junit4] 2> 1137829 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1137829 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
[junit4] 2> 1137829 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1137829 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
[junit4] 2> 1137829 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1137830 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1137830 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
[junit4] 2> 1137830 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1137830 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1137830 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1137831 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1137831 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1137831 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1137831 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1137831 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1137831 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1137831 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 1137832 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1137832 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1137833 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1137834 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1137834 WARN (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.c.SolrCore [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 1137835 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1626713[collection1] main]
[junit4] 2> 1137835 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1137835 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1137836 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1137836 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9f881f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1137836 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1137839 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
[junit4] 2> 1137841 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1137841 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
[junit4] 2> 1137841 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1137841 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
[junit4] 2> 1137842 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
[junit4] 2> 1137842 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1137843 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1137844 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1137846 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1137847 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1137848 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1137848 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1626713[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c10)))}
[junit4] 2> 1137848 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 16
[junit4] 2> 1137850 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documenq&qt=spellCheckCompRH&spellcheck.dictionary=threshold&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=0
[junit4] 2> 1137850 ERROR (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=Path not found: /spellcheck/suggestions/[1]/suggestion
[junit4] 2> expected =/spellcheck/suggestions/[1]/suggestion==[{'word':'document','freq':2}]
[junit4] 2> response = {
[junit4] 2> "responseHeader":{
[junit4] 2> "status":0,
[junit4] 2> "QTime":0},
[junit4] 2> "response":{"numFound":0,"start":0,"docs":[]
[junit4] 2> },
[junit4] 2> "spellcheck":{
[junit4] 2> "suggestions":[],
[junit4] 2> "correctlySpelled":false}}
[junit4] 2>
[junit4] 2> request = q=documenq&qt=spellCheckCompRH&spellcheck.dictionary=threshold&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.extendedResults=true
[junit4] 2> 1137850 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testThresholdTokenFrequency
[junit4] 2> 1137850 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1137850 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1137850 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1137850 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1137851 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@17a872d[collection1] main]
[junit4] 2> 1137851 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1137851 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1137913 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1137987 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1138066 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1138066 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1138169 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1138287 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1138356 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17a872d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1138356 INFO (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 506
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SpellCheckComponentTest -Dtests.method=testThresholdTokenFrequency -Dtests.seed=8B58D135477A65A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=rn -Dtests.timezone=Africa/Bujumbura -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.53s J1 | SpellCheckComponentTest.testThresholdTokenFrequency <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Path not found: /spellcheck/suggestions/[1]/suggestion
[junit4] > at __randomizedtesting.SeedInfo.seed([8B58D135477A65A:821202E2DB9C9F21]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:900)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:847)
[junit4] > at org.apache.solr.handler.component.SpellCheckComponentTest.testThresholdTokenFrequency(SpellCheckComponentTest.java:277)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
[junit4] > at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 1138361 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRelativeIndexDirLocation
[junit4] 2> 1138361 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1138362 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1138362 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1138362 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1138362 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1138363 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1138363 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1138363 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1138363 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1138363 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 1138364 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1138364 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1138368 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@640009[collection1] main]
[junit4] 2> 1138368 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1138368 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1138599 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1138694 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1138825 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1138825 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1138958 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1139077 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1139239 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@640009[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):c10)))}
[junit4] 2> 1139239 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 875
[junit4] 2> 1139240 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRelativeIndexDirLocation
[junit4] 2> 1139240 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1139241 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1139241 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1139241 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1139241 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11bd777[collection1] main]
[junit4] 2> 1139241 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1139241 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1139315 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1139389 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1139464 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1139464 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1139540 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1139592 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1139642 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11bd777[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1139642 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 401
[junit4] 2> 1139644 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRebuildOnCommit
[junit4] 2> 1139644 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1139644 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1139644 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1139645 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1139645 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1139645 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1139645 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1139645 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1139645 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1139646 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 1139646 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1139646 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1139649 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c227a[collection1] main]
[junit4] 2> 1139649 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1139649 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1139805 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1139871 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1140018 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1140018 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1140203 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1140336 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1140469 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c227a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):c10)))}
[junit4] 2> 1140469 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 823
[junit4] 2> 1140470 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:lucenejavt&qt=spellCheckCompRH&spellcheck=true&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1140471 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11231]} 0 0
[junit4] 2> 1140471 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1140471 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1140474 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1713581[collection1] main]
[junit4] 2> 1140474 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1140474 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1140622 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1140666 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1140902 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1140902 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1141054 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1141188 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1141327 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1713581[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):c10) Uninverting(_3(7.0.0):c1)))}
[junit4] 2> 1141327 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 855
[junit4] 2> 1141328 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:lucenejavt&qt=spellCheckCompRH&spellcheck=true&wt=xml} hits=0 status=0 QTime=858
[junit4] 2> 1141328 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRebuildOnCommit
[junit4] 2> 1141328 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1141329 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1141329 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1141329 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1141329 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f01635[collection1] main]
[junit4] 2> 1141329 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1141329 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1141396 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1141486 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1141539 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1141539 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1141606 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1141705 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1141799 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f01635[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1141799 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 470
[junit4] 2> 1141801 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollate
[junit4] 2> 1141801 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1141802 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1141802 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1141802 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1141802 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1141802 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1141802 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1141803 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1141803 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1141803 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 1141803 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1141803 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1141806 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18755e3[collection1] main]
[junit4] 2> 1141806 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1141806 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1142077 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1142145 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1142273 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1142274 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1142425 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1142601 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1142763 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18755e3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):c10)))}
[junit4] 2> 1142763 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 959
[junit4] 2> 1142931 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemt&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.build=true&spellcheck.collate=true} hits=0 status=0 QTime=168
[junit4] 2> 1142932 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemt+lowerfilt:broen^4&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=0
[junit4] 2> 1142933 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemtsss+broens&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=0
[junit4] 2> 1142934 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=pixma&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=0
[junit4] 2> 1142934 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollate
[junit4] 2> 1142934 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1142934 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1142934 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1142935 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1142935 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@12061bb[collection1] main]
[junit4] 2> 1142935 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1142935 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1142995 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1143061 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1143126 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1143126 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1143188 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1143248 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1143300 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12061bb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1143300 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 365
[junit4] 2> 1143302 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExtendedResultsCount
[junit4] 2> 1143302 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1143302 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1143302 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1143303 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1143303 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1143303 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1143303 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1143303 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1143303 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1143304 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 1143304 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1143304 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1143306 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@430fc8[collection1] main]
[junit4] 2> 1143307 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1143307 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1143490 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1143541 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1143685 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1143685 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1143894 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1144065 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1144198 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@430fc8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(7.0.0):c10)))}
[junit4] 2> 1144198 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 894
[junit4] 2> 1144382 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bluo&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.extendedResults=false} hits=0 status=0 QTime=183
[junit4] 2> 1144383 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=bluo&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=3&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=0
[junit4] 2> 1144383 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExtendedResultsCount
[junit4] 2> 1144383 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1144383 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1144383 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1144383 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1144383 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1074fcb[collection1] main]
[junit4] 2> 1144384 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1144384 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1144460 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1144529 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1144614 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1144614 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1144699 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1144756 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1144824 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1074fcb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1144824 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 440
[junit4] 2> 1144825 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReloadOnStart
[junit4] 2> 1144826 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1144826 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1144826 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1144826 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1144826 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1144827 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1144827 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1144827 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1144827 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1144827 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 1144827 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1144827 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1144830 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@112fd04[collection1] main]
[junit4] 2> 1144830 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1144830 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1145049 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1145121 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1145272 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1145272 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1145428 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1145574 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1145693 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@112fd04[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.0.0):c10)))}
[junit4] 2> 1145693 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 865
[junit4] 2> 1145693 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1145694 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1145694 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1145696 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f98053[collection1] main]
[junit4] 2> 1145696 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1145696 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1145859 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1145933 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1146080 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1146080 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1146250 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1146398 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1146594 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f98053[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.0.0):c10/1:delGen=1) Uninverting(_7(7.0.0):c1)))}
[junit4] 2> 1146594 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 900
[junit4] 2> 1146780 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&spellcheck.q=ttle&qt=spellCheckCompRH&spellcheck.dictionary=default&spellcheck=true&wt=xml&spellcheck.build=true} hits=10 status=0 QTime=185
[junit4] 2> 1146781 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1146788 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReloadOnStart
[junit4] 2> 1146788 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1146788 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1146789 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1146789 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1146789 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@19d40de[collection1] main]
[junit4] 2> 1146789 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1146789 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1146855 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1146903 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1146949 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1146949 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1147006 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1147193 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1147243 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@19d40de[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1147243 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 454
[junit4] 2> 1147245 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1147246 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1147246 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1147246 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1147247 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1147247 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1147247 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1147247 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1147247 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1147247 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1147248 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 1147248 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1147248 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1147250 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c4e1a6[collection1] main]
[junit4] 2> 1147250 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1147251 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1147385 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1147444 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1147583 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1147583 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1147720 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1147876 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1148000 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c4e1a6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(7.0.0):c10)))}
[junit4] 2> 1148000 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 752
[junit4] 2> 1148002 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=documemt&qt=spellCheckCompRH&spellcheck=true&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 1148002 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 1148002 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1148002 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1148002 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1148002 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1148002 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e6f296[collection1] main]
[junit4] 2> 1148002 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1148002 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1148088 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1148149 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1148208 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1148208 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1148266 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1148344 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1148428 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e6f296[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1148428 INFO (TEST-SpellCheckComponentTest.test-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 426
[junit4] 2> 1148430 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaximumResultsForSuggest
[junit4] 2> 1148430 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1148431 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 1148431 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1148431 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1148431 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 1148431 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 1148431 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 1148432 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 1148432 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 1148432 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 1148436 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1148436 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1148439 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@107d9dd[collection1] main]
[junit4] 2> 1148439 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1148439 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1148589 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1148665 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1148888 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1148888 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1149070 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1149250 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1149359 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@107d9dd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(7.0.0):c10)))}
[junit4] 2> 1149359 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 922
[junit4] 2> 1149575 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:(this+OR+brwn)&spellcheck.maxResultsForSuggest=7&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.extendedResults=false} hits=7 status=0 QTime=215
[junit4] 2> 1149770 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:(this+OR+brwn)&spellcheck.maxResultsForSuggest=6&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.extendedResults=false} hits=7 status=0 QTime=194
[junit4] 2> 1149770 ERROR (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=Path not found: /spellcheck/suggestions/[1]/numFound
[junit4] 2> expected =/spellcheck/suggestions/[1]/numFound==1
[junit4] 2> response = {
[junit4] 2> "responseHeader":{
[junit4] 2> "status":0,
[junit4] 2> "QTime":194},
[junit4] 2> "command":"build",
[junit4] 2> "response":{"numFound":7,"start":0,"docs":[
[junit4] 2> {
[junit4] 2> "id":4,
[junit4] 2> "range_facet_l":[4],
[junit4] 2> "range_facet_l_dv":[4],
[junit4] 2> "range_facet_i_dv":[4],
[junit4] 2> "lowerfilt":["this blue"],
[junit4] 2> "lowerfilt1and2":["this blue"],
[junit4] 2> "multiDefault":["muLti-Default"],
[junit4] 2> "intDefault":42,
[junit4] 2> "timestamp":"2016-11-21T19:21:05.391Z"},
[junit4] 2> {
[junit4] 2> "id":5,
[junit4] 2> "range_facet_l":[5],
[junit4] 2> "range_facet_l_dv":[5],
[junit4] 2> "range_facet_i_dv":[5],
[junit4] 2> "lowerfilt":["this blud"],
[junit4] 2> "lowerfilt1and2":["this blud"],
[junit4] 2> "multiDefault":["muLti-Default"],
[junit4] 2> "intDefault":42,
[junit4] 2> "timestamp":"2016-11-21T19:21:05.391Z"},
[junit4] 2> {
[junit4] 2> "id":6,
[junit4] 2> "range_facet_l":[6],
[junit4] 2> "range_facet_l_dv":[6],
[junit4] 2> "range_facet_i_dv":[6],
[junit4] 2> "lowerfilt":["this boue"],
[junit4] 2> "lowerfilt1and2":["this boue"],
[junit4] 2> "multiDefault":["muLti-Default"],
[junit4] 2> "intDefault":42,
[junit4] 2> "timestamp":"2016-11-21T19:21:05.391Z"},
[junit4] 2> {
[junit4] 2> "id":7,
[junit4] 2> "range_facet_l":[7],
[junit4] 2> "range_facet_l_dv":[7],
[junit4] 2> "range_facet_i_dv":[7],
[junit4] 2> "lowerfilt":["this glue"],
[junit4] 2> "lowerfilt1and2":["this glue"],
[junit4] 2> "multiDefault":["muLti-Default"],
[junit4] 2> "intDefault":42,
[junit4] 2> "timestamp":"2016-11-21T19:21:05.392Z"},
[junit4] 2> {
[junit4] 2> "id":8,
[junit4] 2> "range_facet_l":[8],
[junit4] 2> "range_facet_l_dv":[8],
[junit4] 2> "range_facet_i_dv":[8],
[junit4] 2> "lowerfilt":["this blee"],
[junit4] 2> "lowerfilt1and2":["this blee"],
[junit4] 2> "multiDefault":["muLti-Default"],
[junit4] 2> "intDefault":42,
[junit4] 2> "timestamp":"2016-11-21T19:21:05.392Z"},
[junit4] 2> {
[junit4] 2> "id":0,
[junit4] 2> "range_facet_l":[0],
[junit4] 2> "range_facet_l_dv":[0],
[junit4] 2> "range_facet_i_dv":[0],
[junit4] 2> "lowerfilt":["This is a title"],
[junit4] 2> "lowerfilt1and2":["This is a title"],
[junit4] 2> "multiDefault":["muLti-Default"],
[junit4] 2> "intDefault":42,
[junit4] 2> "timestamp":"2016-11-21T19:21:05.390Z"},
[junit4] 2> {
[junit4] 2> "id":2,
[junit4] 2> "range_facet_l":[2],
[junit4] 2> "range_facet_l_dv":[2],
[junit4] 2> "range_facet_i_dv":[2],
[junit4] 2> "lowerfilt":["This is a document"],
[junit4] 2> "lowerfilt1and2":["This is a document"],
[junit4] 2> "multiDefault":["muLti-Default"],
[junit4] 2> "intDefault":42,
[junit4] 2> "timestamp":"2016-11-21T19:21:05.391Z"}]
[junit4] 2> },
[junit4] 2> "spellcheck":{
[junit4] 2> "suggestions":[]}}
[junit4] 2>
[junit4] 2> request = q=lowerfilt:(this+OR+brwn)&spellcheck.maxResultsForSuggest=6&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.extendedResults=false
[junit4] 2> 1149966 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:(this+OR+brwn)&spellcheck.maxResultsForSuggest=.90&qt=spellCheckCompRH&spellcheck=true&fq=id:[0+TO+9]&fq=lowerfilt:th*&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.extendedResults=false} hits=7 status=0 QTime=194
[junit4] 2> 1150135 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:(this+OR+brwn)&spellcheck.maxResultsForSuggest=.80&qt=spellCheckCompRH&spellcheck=true&fq=id:[0+TO+9]&fq=lowerfilt:th*&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.extendedResults=false} hits=7 status=0 QTime=168
[junit4] 2> 1150135 ERROR (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=Path not found: /spellcheck/suggestions/[1]/numFound
[junit4] 2> expected =/spellcheck/suggestions/[1]/numFound==1
[junit4] 2> response = {
[junit4] 2> "responseHeader":{
[junit4] 2> "status":0,
[junit4] 2> "QTime":168},
[junit4] 2> "command":"build",
[junit4] 2> "response":{"numFound":7,"start":0,"docs":[
[junit4] 2> {
[junit4] 2> "id":4,
[junit4] 2> "range_facet_l":[4],
[junit4] 2> "range_facet_l_dv":[4],
[junit4] 2> "range_facet_i_dv":[4],
[junit4] 2> "lowerfilt":["this blue"],
[junit4] 2> "lowerfilt1and2":["this blue"],
[junit4] 2> "multiDefault":["muLti-Default"],
[junit4] 2> "intDefault":42,
[junit4] 2> "timestamp":"2016-11-21T19:21:05.391Z"},
[junit4] 2> {
[junit4] 2> "id":5,
[junit4] 2> "range_facet_l":[5],
[junit4] 2>
[...truncated too long message...]
utor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1154440 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1154635 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1154820 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@167b7ed[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(7.0.0):c10)))}
[junit4] 2> 1154820 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 974
[junit4] 2> 1154820 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:lazy+lowerfilt:brown&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.extendedResults=true} hits=1 status=0 QTime=0
[junit4] 2> 1154821 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:lazy+lowerfilt:brown&qt=spellCheckCompRH&spellcheck.dictionary=direct_lowerfilt&json.nl=map&spellcheck=true&wt=xml&spellcheck.extendedResults=true} hits=1 status=0 QTime=0
[junit4] 2> 1154827 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lakkle&qt=spellCheckCompRH&spellcheck.dictionary=direct_lowerfilt&json.nl=map&spellcheck=true&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=5
[junit4] 2> 1154827 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCorrectSpelling
[junit4] 2> 1154827 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1154827 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1154827 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1154827 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@aed989
[junit4] 2> 1154828 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@dd18c[collection1] main]
[junit4] 2> 1154828 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1154828 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 1154903 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 1154965 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 1155043 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 1155043 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 1155108 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 1155182 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 1155257 INFO (searcherExecutor-4050-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@dd18c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1155257 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[8B58D135477A65A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 429
[junit4] 2> 1155258 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1155258 INFO (SUITE-SpellCheckComponentTest-seed#[8B58D135477A65A]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=13099583
[junit4] 2> 1155258 INFO (coreCloseExecutor-4054-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e13266
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_8B58D135477A65A-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {start1=PostingsFormat(name=Memory), range_facet_l_dv=Lucene50(blocksize=128), multiDefault=FSTOrd50, intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), lowerfilt1and2=FSTOrd50, end4=PostingsFormat(name=Memory), end3=FSTOrd50, end2=Lucene50(blocksize=128), end1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), lowerfilt=FSTOrd50, gram1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=Lucene50(blocksize=128), range_facet_i_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), gram2=Lucene50(blocksize=128), gram3=FSTOrd50, start3=Lucene50(blocksize=128), gram4=PostingsFormat(name=Memory), start2=FSTOrd50, word=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), timestamp=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), start4=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=816, maxMBSortInHeap=5.951077510252567, sim=RandomSimilarity(queryNorm=false): {}, locale=rn, timezone=Africa/Bujumbura
[junit4] 2> NOTE: Linux 4.4.0-47-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=42144160,total=370409472
[junit4] 2> NOTE: All tests run in this JVM: [TestFastLRUCache, TestAddFieldRealTimeGet, FileUtilsTest, UtilsToolTest, TestSchemaManager, TestFiltering, TestScoreJoinQPScore, TestSolrQueryParserDefaultOperatorResource, SchemaVersionSpecificBehaviorTest, TestCloudPseudoReturnFields, DirectSolrConnectionTest, TestElisionMultitermQuery, TestManagedStopFilterFactory, AnalyticsMergeStrategyTest, SolrIndexSplitterTest, DirectoryFactoryTest, SuggestComponentTest, TestNRTOpen, TestOnReconnectListenerSupport, BlockDirectoryTest, TestReRankQParserPlugin, CollectionReloadTest, OpenExchangeRatesOrgProviderTest, SpatialFilterTest, LeaderFailoverAfterPartitionTest, IndexBasedSpellCheckerTest, ShufflingReplicaListTransformerTest, ClassificationUpdateProcessorFactoryTest, TermsComponentTest, DistributedFacetPivotLongTailTest, CollectionsAPIDistributedZkTest, ZkStateReaderTest, DistributedFacetPivotWhiteBoxTest, TestSimpleTrackingShardHandler, IgnoreCommitOptimizeUpdateProcessorFactoryTest, RestartWhileUpdatingTest, TestAnalyzeInfixSuggestions, CdcrReplicationHandlerTest, TestMiniSolrCloudCluster, MoreLikeThisHandlerTest, TestBM25SimilarityFactory, OverseerModifyCollectionTest, HttpSolrCallGetCoreTest, ChaosMonkeyNothingIsSafeTest, TestSurroundQueryParser, TestPKIAuthenticationPlugin, TestReplicationHandlerBackup, TestSearcherReuse, TestJettySolrRunner, TestDistributedGrouping, TestXIncludeConfig, TestStressRecovery, TestSystemIdResolver, SampleTest, HdfsBasicDistributedZkTest, TestStressVersions, JSONWriterTest, TestLegacyFieldCache, BJQParserTest, TestRequestStatusCollectionAPI, TestSubQueryTransformer, DOMUtilTest, TestConfigSetsAPIZkFailure, TestCharFilters, SolrCloudExampleTest, BlockJoinFacetSimpleTest, TestDistribDocBasedVersion, SortByFunctionTest, TestExactStatsCache, QueryParsingTest, SolrInfoMBeanTest, TestFilteredDocIdSet, ForceLeaderTest, DocValuesMissingTest, TestDocumentBuilder, HighlighterMaxOffsetTest, RuleEngineTest, TestQueryWrapperFilter, ZkControllerTest, CoreMergeIndexesAdminHandlerTest, CustomCollectionTest, TestUpdate, HdfsCollectionsAPIDistributedZkTest, TestConfigSetImmutable, TestRTimerTree, TestCoreContainer, TestSolrCloudWithSecureImpersonation, TestSearchPerf, SharedFSAutoReplicaFailoverUtilsTest, AliasIntegrationTest, ConfigSetsAPITest, TestRawTransformer, DeleteNodeTest, TestQueryTypes, TestPostingsSolrHighlighter, DocValuesTest, TestBlobHandler, TestSweetSpotSimilarityFactory, TestSolrConfigHandlerConcurrent, TestFuzzyAnalyzedSuggestions, ClusterStateUpdateTest, TestWordDelimiterFilterFactory, TestDefaultSearchFieldResource, TestNonDefinedSimilarityFactory, SimplePostToolTest, FullSolrCloudDistribCmdsTest, TestRandomDVFaceting, ShardRoutingCustomTest, TestHashPartitioner, TestJoin, HardAutoCommitTest, StatsComponentTest, SpellCheckComponentTest]
[junit4] Completed [281/652 (1!)] on J1 in 18.35s, 11 tests, 1 error <<< FAILURES!
[...truncated 52581 lines...]