Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2194/
4 tests failed.
FAILED: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test
Error Message:
Expected 2 of 3 replicas to be active but only found 1;
[core_node5:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43463","node_name":"127.0.0.1:43463_","state":"active","type":"NRT","leader":"true"}];
clusterState:
DocCollection(c8n_1x3_lf//collections/c8n_1x3_lf/state.json/17)={
"pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{
"range":"80000000-7fffffff", "state":"active", "replicas":{
"core_node3":{ "core":"c8n_1x3_lf_shard1_replica_n1",
"base_url":"http://127.0.0.1:44607";, "node_name":"127.0.0.1:44607_",
"state":"down", "type":"NRT"}, "core_node5":{
"core":"c8n_1x3_lf_shard1_replica_n2",
"base_url":"http://127.0.0.1:43463";, "node_name":"127.0.0.1:43463_",
"state":"active", "type":"NRT", "leader":"true"},
"core_node6":{ "state":"down",
"base_url":"http://127.0.0.1:46451";,
"core":"c8n_1x3_lf_shard1_replica_n4",
"node_name":"127.0.0.1:46451_", "type":"NRT"}}}},
"router":{"name":"compositeId"}, "maxShardsPerNode":"1",
"autoAddReplicas":"false", "nrtReplicas":"3", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Expected 2 of 3 replicas to be active but only found
1;
[core_node5:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43463","node_name":"127.0.0.1:43463_","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//collections/c8n_1x3_lf/state.json/17)={
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node3":{
"core":"c8n_1x3_lf_shard1_replica_n1",
"base_url":"http://127.0.0.1:44607";,
"node_name":"127.0.0.1:44607_",
"state":"down",
"type":"NRT"},
"core_node5":{
"core":"c8n_1x3_lf_shard1_replica_n2",
"base_url":"http://127.0.0.1:43463";,
"node_name":"127.0.0.1:43463_",
"state":"active",
"type":"NRT",
"leader":"true"},
"core_node6":{
"state":"down",
"base_url":"http://127.0.0.1:46451";,
"core":"c8n_1x3_lf_shard1_replica_n4",
"node_name":"127.0.0.1:46451_",
"type":"NRT"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([C083DED148923B3:845C0237BA754E4B]: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:169)
at
org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFailoverAfterPartitionTest.java:56)
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:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.ShardSplitTest.testSplitAfterFailedSplit
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at
__randomizedtesting.SeedInfo.seed([C083DED148923B3:F545AE4228FC6E39]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.ShardSplitTest.testSplitAfterFailedSplit(ShardSplitTest.java:279)
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:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.SystemLogListenerTest.test
Error Message:
wrong number of events added to .system expected:<9> but was:<10>
Stack Trace:
java.lang.AssertionError: wrong number of events added to .system expected:<9>
but was:<10>
at
__randomizedtesting.SeedInfo.seed([C083DED148923B3:845C0237BA754E4B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.cloud.autoscaling.SystemLogListenerTest.test(SystemLogListenerTest.java:166)
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:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.TestPKIAuthenticationPlugin.test
Error Message:
Stack Trace:
java.lang.NullPointerException
at
__randomizedtesting.SeedInfo.seed([C083DED148923B3:845C0237BA754E4B]:0)
at
org.apache.solr.security.TestPKIAuthenticationPlugin.test(TestPKIAuthenticationPlugin.java:102)
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:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 11576 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.SystemLogListenerTest
[junit4] 2> 96376 INFO
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/init-core-data-001
[junit4] 2> 96377 WARN
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 96378 INFO
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 96395 INFO
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 96396 INFO
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001
[junit4] 2> 96396 INFO
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 96397 INFO (Thread-63) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 96397 INFO (Thread-63) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 96411 ERROR (Thread-63) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 96504 INFO
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:42905
[junit4] 2> 96619 INFO (jetty-launcher-54-thread-1) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 96635 INFO (jetty-launcher-54-thread-2) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 96672 INFO (jetty-launcher-54-thread-3) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 96749 INFO (jetty-launcher-54-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@52df5fb6{/solr,null,AVAILABLE}
[junit4] 2> 96750 INFO (jetty-launcher-54-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@35baa6e7{HTTP/1.1,[http/1.1]}{127.0.0.1:38364}
[junit4] 2> 96750 INFO (jetty-launcher-54-thread-3) [ ] o.e.j.s.Server
Started @111030ms
[junit4] 2> 96750 INFO (jetty-launcher-54-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38364}
[junit4] 2> 96751 ERROR (jetty-launcher-54-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 96751 INFO (jetty-launcher-54-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 96751 INFO (jetty-launcher-54-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 96751 INFO (jetty-launcher-54-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 96751 INFO (jetty-launcher-54-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-27T11:16:05.001Z
[junit4] 2> 96980 INFO (jetty-launcher-54-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@651cce9f{/solr,null,AVAILABLE}
[junit4] 2> 96981 INFO (jetty-launcher-54-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4dcbd8bb{HTTP/1.1,[http/1.1]}{127.0.0.1:37175}
[junit4] 2> 96981 INFO (jetty-launcher-54-thread-1) [ ] o.e.j.s.Server
Started @111261ms
[junit4] 2> 96981 INFO (jetty-launcher-54-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37175}
[junit4] 2> 96981 ERROR (jetty-launcher-54-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 96981 INFO (jetty-launcher-54-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 96981 INFO (jetty-launcher-54-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 96981 INFO (jetty-launcher-54-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 96982 INFO (jetty-launcher-54-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-27T11:16:05.231Z
[junit4] 2> 97069 INFO (jetty-launcher-54-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@150c3fa6{/solr,null,AVAILABLE}
[junit4] 2> 97070 INFO (jetty-launcher-54-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3b8e7307{HTTP/1.1,[http/1.1]}{127.0.0.1:40953}
[junit4] 2> 97070 INFO (jetty-launcher-54-thread-2) [ ] o.e.j.s.Server
Started @111350ms
[junit4] 2> 97070 INFO (jetty-launcher-54-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40953}
[junit4] 2> 97070 ERROR (jetty-launcher-54-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 97070 INFO (jetty-launcher-54-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 97070 INFO (jetty-launcher-54-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 97070 INFO (jetty-launcher-54-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 97070 INFO (jetty-launcher-54-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-27T11:16:05.320Z
[junit4] 2> 97146 INFO (jetty-launcher-54-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 97292 INFO (jetty-launcher-54-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 97309 INFO (jetty-launcher-54-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 97326 INFO (jetty-launcher-54-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42905/solr
[junit4] 2> 97725 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 0x15ffd30f3a80004, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 97798 INFO (jetty-launcher-54-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42905/solr
[junit4] 2> 97816 INFO (jetty-launcher-54-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42905/solr
[junit4] 2> 98725 INFO (jetty-launcher-54-thread-1)
[n:127.0.0.1:37175_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 98725 INFO (jetty-launcher-54-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 98871 INFO (jetty-launcher-54-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:38364_solr
[junit4] 2> 98908 INFO (jetty-launcher-54-thread-1)
[n:127.0.0.1:37175_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37175_solr
[junit4] 2> 98909 INFO (jetty-launcher-54-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.Overseer Overseer
(id=99076103513571333-127.0.0.1:38364_solr-n_0000000000) starting
[junit4] 2> 99054 INFO
(zkCallback-71-thread-1-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 99055 INFO
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 99156 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 99249 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 99265 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:37175_solr]
[junit4] 2> 99265 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 99265 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 99275 INFO (jetty-launcher-54-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38364_solr
[junit4] 2> 99330 INFO
(zkCallback-71-thread-1-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 99330 INFO
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 99346 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 99347 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 99347 DEBUG (ScheduledTrigger-94-thread-1)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr]
[junit4] 2> 100038 INFO (jetty-launcher-54-thread-1)
[n:127.0.0.1:37175_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37175.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 100149 INFO (jetty-launcher-54-thread-1)
[n:127.0.0.1:37175_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37175.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 100149 INFO (jetty-launcher-54-thread-1)
[n:127.0.0.1:37175_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37175.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 100220 INFO (jetty-launcher-54-thread-1)
[n:127.0.0.1:37175_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node1/.
[junit4] 2> 100432 DEBUG (ScheduledTrigger-94-thread-1)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr]
[junit4] 2> 101971 DEBUG (ScheduledTrigger-94-thread-1)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr]
[junit4] 2> 101993 INFO (jetty-launcher-54-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38364.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 102117 INFO (jetty-launcher-54-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38364.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 102117 INFO (jetty-launcher-54-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38364.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 102119 INFO (jetty-launcher-54-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node3/.
[junit4] 2> 103127 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr]
[junit4] 2> 103203 INFO (jetty-launcher-54-thread-2)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 103219 INFO (jetty-launcher-54-thread-2)
[n:127.0.0.1:40953_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 103238 INFO (jetty-launcher-54-thread-2)
[n:127.0.0.1:40953_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40953_solr
[junit4] 2> 103274 INFO
(zkCallback-71-thread-1-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 103295 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 103295 INFO
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 104134 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 104174 INFO (jetty-launcher-54-thread-2)
[n:127.0.0.1:40953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40953.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 104211 INFO (jetty-launcher-54-thread-2)
[n:127.0.0.1:40953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40953.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 104224 INFO (jetty-launcher-54-thread-2)
[n:127.0.0.1:40953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40953.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 104226 INFO (jetty-launcher-54-thread-2)
[n:127.0.0.1:40953_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node2/.
[junit4] 2> 104847 INFO
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 104848 INFO
(SUITE-SystemLogListenerTest-seed#[C083DED148923B3]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42905/solr ready
[junit4] 2> 105008 INFO (qtp560527802-287) [n:127.0.0.1:38364_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=3&name=.system&nrtReplicas=3&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 105138 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 105175 INFO
(OverseerThreadFactory-96-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.CreateCollectionCmd Create collection
.system
[junit4] 2> 105497 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40953/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 105667 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38364/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 105715 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37175/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 106179 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 106180 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 106191 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node5&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 106194 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 106195 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 106196 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node6&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n4&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 106196 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 106508 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 106508 INFO
(zkCallback-71-thread-1-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 106515 INFO
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 106544 INFO
(zkCallback-72-thread-2-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 106545 INFO
(zkCallback-63-thread-2-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 106545 INFO
(zkCallback-71-thread-2-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 107206 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 107447 WARN (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.c.Config You
should not use LATEST as luceneMatchVersion property: if you use this setting,
and then Solr upgrades to a newer release of Lucene, sizable changes may
happen. If precise back compatibility is important then you should instead
explicitly specify an actual Lucene version.
[junit4] 2> 107525 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 107563 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 107582 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 108071 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but
managed schema resource managed-schema not found - loading non-managed schema
schema.xml instead
[junit4] 2> 108087 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but
managed schema resource managed-schema not found - loading non-managed schema
schema.xml instead
[junit4] 2> 108209 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 108270 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection
or core
[junit4] 2> 108270 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but
managed schema resource managed-schema not found - loading non-managed schema
schema.xml instead
[junit4] 2> 108290 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection
or core
[junit4] 2> 108336 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.IndexSchema [.system_shard1_replica_n4] Schema name=_system collection
or core
[junit4] 2> 108439 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid
field id
[junit4] 2> 108456 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid
field id
[junit4] 2> 108473 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid
field id
[junit4] 2> 108591 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at
/configs/.system/managed-schema
[junit4] 2> 108594 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in
ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to
/configs/.system/schema.xml.bak
[junit4] 2> 108594 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n4' using
configuration from collection .system, trusted=true
[junit4] 2> 108717 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37175.solr.core..system.shard1.replica_n4' (registry
'solr.core..system.shard1.replica_n4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 108717 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 108717 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.c.SolrCore
[[.system_shard1_replica_n4] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node1/.system_shard1_replica_n4],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node1/./.system_shard1_replica_n4/data/]
[junit4] 2> 108619 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.ManagedIndexSchema Managed schema znode at
/configs/.system/managed-schema already exists - no need to create it
[junit4] 2> 108619 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.ManagedIndexSchema Managed schema znode at
/configs/.system/managed-schema already exists - no need to create it
[junit4] 2> 108798 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in
ZooKeeper, the non-managed schema /configs/.system/schema.xml no longer exists.
[junit4] 2> 108798 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n2' using
configuration from collection .system, trusted=true
[junit4] 2> 108799 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38364.solr.core..system.shard1.replica_n2' (registry
'solr.core..system.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 108799 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 108799 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.c.SolrCore
[[.system_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node3/.system_shard1_replica_n2],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node3/./.system_shard1_replica_n2/data/]
[junit4] 2> 108820 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in
ZooKeeper, the non-managed schema /configs/.system/schema.xml no longer exists.
[junit4] 2> 108820 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using
configuration from collection .system, trusted=true
[junit4] 2> 108821 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40953.solr.core..system.shard1.replica_n1' (registry
'solr.core..system.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@59b9e689
[junit4] 2> 108821 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 108821 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.c.SolrCore
[[.system_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node2/.system_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SystemLogListenerTest_C083DED148923B3-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
[junit4] 2> 109212 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 109671 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 109671 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 109672 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs
[junit4] 2> 109672 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 109710 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3c17edae[.system_shard1_replica_n1]
main]
[junit4] 2> 109744 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 109744 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 109745 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs
[junit4] 2> 109746 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 109764 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/.system
[junit4] 2> 109764 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 109766 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/.system/managed-schema
[junit4] 2> 109780 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 109780 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 109782 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs
[junit4] 2> 109782 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 109801 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
[junit4] 2> 109802 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3bda087f[.system_shard1_replica_n2]
main]
[junit4] 2> 109803 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/.system
[junit4] 2> 109803 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 109804 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.SolrIndexSearcher Opening [Searcher@60905214[.system_shard1_replica_n4]
main]
[junit4] 2> 109804 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/.system/managed-schema
[junit4] 2> 109893 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection
or core
[junit4] 2> 109948 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
[junit4] 2> 109984 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection
or core
[junit4] 2> 110040 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/.system
[junit4] 2> 110095 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid
field id
[junit4] 2> 110095 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 147 ms
[junit4] 2> 110096 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 110096 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/.system/managed-schema
[junit4] 2> 110096 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 110110 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid
field id
[junit4] 2> 110110 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1585217670595215360
[junit4] 2> 110110 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 308 ms
[junit4] 2> 110110 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 110111 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1585217670596263936
[junit4] 2> 110127 INFO
(searcherExecutor-105-thread-1-processing-n:127.0.0.1:40953_solr
x:.system_shard1_replica_n1 s:shard1 c:.system r:core_node3)
[n:127.0.0.1:40953_solr c:.system s:shard1 r:core_node3
x:.system_shard1_replica_n1] o.a.s.c.SolrCore [.system_shard1_replica_n1]
Registered new searcher Searcher@3c17edae[.system_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 110257 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 110258 INFO
(searcherExecutor-104-thread-1-processing-n:127.0.0.1:38364_solr
x:.system_shard1_replica_n2 s:shard1 c:.system r:core_node5)
[n:127.0.0.1:38364_solr c:.system s:shard1 r:core_node5
x:.system_shard1_replica_n2] o.a.s.c.SolrCore [.system_shard1_replica_n2]
Registered new searcher Searcher@3bda087f[.system_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 110272 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 110272 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 110274 INFO
(searcherExecutor-103-thread-1-processing-n:127.0.0.1:37175_solr
x:.system_shard1_replica_n4 s:shard1 c:.system r:core_node6)
[n:127.0.0.1:37175_solr c:.system s:shard1 r:core_node6
x:.system_shard1_replica_n4] o.a.s.c.SolrCore [.system_shard1_replica_n4]
Registered new searcher Searcher@60905214[.system_shard1_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 110275 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1585217670768230400
[junit4] 2> 110311 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=3 found=2 timeoutin=9999ms
[junit4] 2> 110332 INFO
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 110332 INFO
(zkCallback-63-thread-2-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 110368 INFO
(zkCallback-71-thread-2-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 110368 INFO
(zkCallback-71-thread-1-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 110401 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 110410 INFO
(zkCallback-72-thread-2-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 110820 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 110820 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 110820 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:40953/solr/.system_shard1_replica_n1/
[junit4] 2> 110821 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.u.PeerSync
PeerSync: core=.system_shard1_replica_n1 url=http://127.0.0.1:40953/solr START
replicas=[http://127.0.0.1:38364/solr/.system_shard1_replica_n2/,
http://127.0.0.1:37175/solr/.system_shard1_replica_n4/] nUpdates=100
[junit4] 2> 111033 INFO (qtp560527802-289) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.c.S.Request
[.system_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=16
[junit4] 2> 111034 INFO (qtp1308514057-298) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.c.S.Request
[.system_shard1_replica_n4] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=14
[junit4] 2> 111214 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.u.PeerSync
PeerSync: core=.system_shard1_replica_n1 url=http://127.0.0.1:40953/solr DONE.
We have no versions. sync failed.
[junit4] 2> 111214 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 111214 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 111214 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 111396 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 111433 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:40953/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 111689 INFO
(zkCallback-72-thread-2-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 111689 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 111689 INFO
(zkCallback-71-thread-1-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 111689 INFO
(zkCallback-71-thread-2-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 111701 INFO
(zkCallback-63-thread-3-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 111701 INFO
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 111741 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 111835 INFO (qtp1751198621-293) [n:127.0.0.1:40953_solr
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=5655
[junit4] 2> 111856 INFO (qtp560527802-285) [n:127.0.0.1:38364_solr
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=5664
[junit4] 2> 111868 INFO (qtp1308514057-296) [n:127.0.0.1:37175_solr
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n4&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=5672
[junit4] 2> 112177 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 112177 INFO
(zkCallback-72-thread-2-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 112178 INFO
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 112178 INFO
(zkCallback-63-thread-2-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 112179 INFO
(zkCallback-71-thread-2-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 112180 INFO
(zkCallback-71-thread-1-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/.system/state.json] for collection [.system] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 112217 INFO (qtp560527802-287) [n:127.0.0.1:38364_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> 112217 INFO (qtp560527802-287) [n:127.0.0.1:38364_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=3&name=.system&nrtReplicas=3&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=7208
[junit4] 2> 112472 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 112731 INFO
(TEST-SystemLogListenerTest.test-seed#[C083DED148923B3]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 113232 INFO (qtp1751198621-294) [n:127.0.0.1:40953_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 113233 INFO (qtp1751198621-294) [n:127.0.0.1:40953_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42905/solr ready
[junit4] 2> 113269 INFO (qtp560527802-291) [n:127.0.0.1:38364_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 113290 INFO (qtp1308514057-303) [n:127.0.0.1:37175_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 113425 INFO (qtp1751198621-311) [n:127.0.0.1:40953_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 113434 DEBUG (qtp1751198621-294) [n:127.0.0.1:40953_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 113561 DEBUG (ScheduledTrigger-94-thread-3)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 113562 DEBUG
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 113562 INFO (qtp1751198621-294) [n:127.0.0.1:40953_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=672
[junit4] 2> 113692 INFO
(OverseerCollectionConfigSetProcessor-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_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> 113744 DEBUG
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38364_solr, 127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 113745 DEBUG
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38364_solr, 127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 113759 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 113780 INFO (qtp1308514057-304) [n:127.0.0.1:37175_solr ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 113781 INFO (qtp1308514057-304) [n:127.0.0.1:37175_solr ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42905/solr ready
[junit4] 2> 113818 INFO (qtp560527802-291) [n:127.0.0.1:38364_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 113875 INFO (qtp1308514057-305) [n:127.0.0.1:37175_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 113901 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 113912 INFO (qtp1751198621-311) [n:127.0.0.1:40953_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 113921 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 113921 DEBUG (ScheduledTrigger-94-thread-4)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 113926 DEBUG (qtp1308514057-304) [n:127.0.0.1:37175_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 113984 DEBUG
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 113985 INFO (qtp1308514057-304) [n:127.0.0.1:37175_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=293
[junit4] 2> 113998 DEBUG
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38364_solr, 127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 113999 DEBUG
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38364_solr, 127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 113999 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 114000 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 114000 DEBUG
(OverseerAutoScalingTriggerThread-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 114002 INFO (qtp560527802-288) [n:127.0.0.1:38364_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&maxShardsPerNode=3&name=test&nrtReplicas=2&action=CREATE&numShards=3&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 114120 INFO
(OverseerThreadFactory-96-thread-2-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.CreateCollectionCmd Create collection test
[junit4] 2> 114561 DEBUG (ScheduledTrigger-94-thread-2)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 114750 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40953/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 114913 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37175/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 114977 DEBUG (ScheduledTrigger-94-thread-2)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 115079 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"test_shard2_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38364/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 115242 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"test_shard2_replica_n7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40953/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 115409 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"test_shard3_replica_n9",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37175/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 115561 DEBUG (ScheduledTrigger-94-thread-2)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 115591 INFO
(OverseerStateUpdate-99076103513571333-127.0.0.1:38364_solr-n_0000000000)
[n:127.0.0.1:38364_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"test_shard3_replica_n11",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38364/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 115801 INFO (qtp1751198621-297) [n:127.0.0.1:40953_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=3&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 115802 INFO (qtp560527802-287) [n:127.0.0.1:38364_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=test_shard2_replica_n5&action=CREATE&numShards=3&collection=test&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 115803 INFO (qtp1751198621-310) [n:127.0.0.1:40953_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=test_shard2_replica_n7&action=CREATE&numShards=3&collection=test&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 115855 INFO (qtp1308514057-298) [n:127.0.0.1:37175_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=test_shard3_replica_n9&action=CREATE&numShards=3&collection=test&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 115859 INFO (qtp560527802-291) [n:127.0.0.1:38364_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=test_shard3_replica_n11&action=CREATE&numShards=3&collection=test&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 115869 INFO (qtp1308514057-302) [n:127.0.0.1:37175_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=test_shard1_replica_n3&action=CREATE&numShards=3&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 115979 DEBUG (ScheduledTrigger-94-thread-2)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 116069 INFO
(zkCallback-71-thread-2-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 116073 INFO
(zkCallback-71-thread-1-processing-n:127.0.0.1:37175_solr)
[n:127.0.0.1:37175_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 116073 INFO
(zkCallback-63-thread-1-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 116073 INFO
(zkCallback-63-thread-2-processing-n:127.0.0.1:38364_solr)
[n:127.0.0.1:38364_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 116073 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 116073 INFO
(zkCallback-72-thread-2-processing-n:127.0.0.1:40953_solr)
[n:127.0.0.1:40953_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test/state.json] for collection [test] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 116564 DEBUG (ScheduledTrigger-94-thread-2)
[n:127.0.0.1:38364_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: [127.0.0.1:38364_solr,
127.0.0.1:37175_solr, 127.0.0.1:40953_solr]
[junit4] 2> 117059 DEBUG (ScheduledTrigger-94-thread-2)
[n:127.0.0.1:38364_solr ] o
[...truncated too long message...]
FailoverAfterPartitionTest.test-seed#[C083DED148923B3]) [ ]
o.a.s.c.SocketProxy Closing 4 connections to: http://127.0.0.1:41339/, target:
http://127.0.0.1:44851/
[junit4] 2> 1272349 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[C083DED148923B3]) [ ]
o.a.s.c.SocketProxy Closing 2 connections to: http://127.0.0.1:46451/, target:
http://127.0.0.1:33079/
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=LeaderFailoverAfterPartitionTest -Dtests.method=test
-Dtests.seed=C083DED148923B3 -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=fr-BE -Dtests.timezone=America/Indiana/Vincennes
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 162s J1 | LeaderFailoverAfterPartitionTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected 2 of 3
replicas to be active but only found 1;
[core_node5:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43463","node_name":"127.0.0.1:43463_","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//collections/c8n_1x3_lf/state.json/17)={
[junit4] > "pullReplicas":"0",
[junit4] > "replicationFactor":"1",
[junit4] > "shards":{"shard1":{
[junit4] > "range":"80000000-7fffffff",
[junit4] > "state":"active",
[junit4] > "replicas":{
[junit4] > "core_node3":{
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n1",
[junit4] > "base_url":"http://127.0.0.1:44607";,
[junit4] > "node_name":"127.0.0.1:44607_",
[junit4] > "state":"down",
[junit4] > "type":"NRT"},
[junit4] > "core_node5":{
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n2",
[junit4] > "base_url":"http://127.0.0.1:43463";,
[junit4] > "node_name":"127.0.0.1:43463_",
[junit4] > "state":"active",
[junit4] > "type":"NRT",
[junit4] > "leader":"true"},
[junit4] > "core_node6":{
[junit4] > "state":"down",
[junit4] > "base_url":"http://127.0.0.1:46451";,
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n4",
[junit4] > "node_name":"127.0.0.1:46451_",
[junit4] > "type":"NRT"}}}},
[junit4] > "router":{"name":"compositeId"},
[junit4] > "maxShardsPerNode":"1",
[junit4] > "autoAddReplicas":"false",
[junit4] > "nrtReplicas":"3",
[junit4] > "tlogReplicas":"0"}
[junit4] > at
__randomizedtesting.SeedInfo.seed([C083DED148923B3:845C0237BA754E4B]:0)
[junit4] > at
org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.testRf3WithLeaderFailover(LeaderFailoverAfterPartitionTest.java:169)
[junit4] > at
org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFailoverAfterPartitionTest.java:56)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_C083DED148923B3-001
[junit4] 2> Nov 27, 2017 11:35:40 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{multiDefault=PostingsFormat(name=Asserting),
a_t=PostingsFormat(name=LuceneFixedGap),
id=PostingsFormat(name=LuceneVarGapFixedInterval),
text=PostingsFormat(name=Memory)},
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70),
_version_=DocValuesFormat(name=Memory),
range_facet_i_dv=DocValuesFormat(name=Memory),
intDvoDefault=DocValuesFormat(name=Direct),
timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=489,
maxMBSortInHeap=7.009516506076839,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@328d2369),
locale=fr-BE, timezone=America/Indiana/Vincennes
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=1,free=194791504,total=526909440
[junit4] 2> NOTE: All tests run in this JVM: [TestUtilizeNode,
SystemLogListenerTest, TestCloudInspectUtil, TestRemoteStreaming,
OverseerModifyCollectionTest, NumericFieldsTest, QueryEqualityTest,
ShufflingReplicaListTransformerTest, TestFieldCollectionResource,
TestStressLucene, TestSolrConfigHandlerCloud, RulesTest, TestMultiWordSynonyms,
SynonymTokenizerTest, DocExpirationUpdateProcessorFactoryTest,
TestManagedSynonymGraphFilterFactory, ChangedSchemaMergeTest,
TestSurroundQueryParser, TestSolrQueryResponse,
OpenExchangeRatesOrgProviderTest, BadIndexSchemaTest, DebugComponentTest,
HighlighterConfigTest, TestHdfsUpdateLog, TestDistribIDF, SearchHandlerTest,
TestXIncludeConfig, TestFieldCache, SignatureUpdateProcessorFactoryTest,
TestQuerySenderNoQuery, TestCryptoKeys, TestSolrCLIRunExample,
ShardRoutingTest, TestSimpleTrackingShardHandler,
LeaderFailoverAfterPartitionTest]
[junit4] Completed [82/751 (3!)] on J1 in 162.78s, 1 test, 1 failure <<<
FAILURES!
[...truncated 364 lines...]
[junit4] Suite: org.apache.solr.security.TestPKIAuthenticationPlugin
[junit4] 2> 2534343 INFO
(SUITE-TestPKIAuthenticationPlugin-seed#[C083DED148923B3]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.security.TestPKIAuthenticationPlugin_C083DED148923B3-001/init-core-data-001
[junit4] 2> 2534344 WARN
(SUITE-TestPKIAuthenticationPlugin-seed#[C083DED148923B3]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 2534363 INFO
(SUITE-TestPKIAuthenticationPlugin-seed#[C083DED148923B3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2534364 INFO
(SUITE-TestPKIAuthenticationPlugin-seed#[C083DED148923B3]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2534400 INFO
(TEST-TestPKIAuthenticationPlugin.test-seed#[C083DED148923B3]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2551051 ERROR
(TEST-TestPKIAuthenticationPlugin.test-seed#[C083DED148923B3]) [ ]
o.a.s.s.PKIAuthenticationPlugin Invalid key request timestamp: 1511783802332 ,
received timestamp: 1511783818634 , TTL: 10000
[junit4] 2> 2551052 INFO
(TEST-TestPKIAuthenticationPlugin.test-seed#[C083DED148923B3]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestPKIAuthenticationPlugin -Dtests.method=test
-Dtests.seed=C083DED148923B3 -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=ar-JO -Dtests.timezone=Australia/Melbourne -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 16.7s J0 | TestPKIAuthenticationPlugin.test <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([C083DED148923B3:845C0237BA754E4B]:0)
[junit4] > at
org.apache.solr.security.TestPKIAuthenticationPlugin.test(TestPKIAuthenticationPlugin.java:102)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.security.TestPKIAuthenticationPlugin_C083DED148923B3-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1868, maxMBSortInHeap=5.749194716934916,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5c1d19fc),
locale=ar-JO, timezone=Australia/Melbourne
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=1,free=151720840,total=471859200
[junit4] 2> NOTE: All tests run in this JVM: [TestSegmentSorting,
AutoAddReplicasIntegrationTest, SharedFSAutoReplicaFailoverTest,
SolrSlf4jReporterTest, LeaderInitiatedRecoveryOnCommitTest, DocValuesTest,
SpatialFilterTest, DistributedSuggestComponentTest, TestManagedResource,
TestXmlQParser, TestCSVLoader, HdfsThreadLeakTest,
TestOverriddenPrefixQueryForCustomFieldType, SpatialRPTFieldTypeTest,
StatelessScriptUpdateProcessorFactoryTest, TestSchemaResource,
TestManagedSynonymFilterFactory, TestCloudNestedDocsSort,
TestPHPSerializedResponseWriter, ExternalFileFieldSortTest,
TestSerializedLuceneMatchVersion, TestConfigsApi,
SuggestComponentContextFilterQueryTest,
AddSchemaFieldsUpdateProcessorFactoryTest, TestDelegationWithHadoopAuth,
TestSolrXml, TestDynamicLoading, AnalysisErrorHandlingTest,
URLClassifyProcessorTest, TestConfigOverlay, TestCloudDeleteByQuery, TestUtils,
RecoveryAfterSoftCommitTest, TestDistributedSearch, DistribCursorPagingTest,
TestStressVersions, TestJsonFacets, NodeMutatorTest, DeleteReplicaTest,
TestSkipOverseerOperations, TestComponentsName, DateRangeFieldTest,
SearchHandlerTest, SolrMetricReporterTest, WrapperMergePolicyFactoryTest,
TestSweetSpotSimilarityFactory, TestCloudSchemaless, NoCacheHeaderTest,
TestRandomDVFaceting, ComputePlanActionTest, DistributedExpandComponentTest,
TestFastOutputStream, TestSubQueryTransformer, TestPKIAuthenticationPlugin]
[junit4] Completed [185/751 (4!)] on J0 in 16.90s, 1 test, 1 error <<<
FAILURES!
[...truncated 49210 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
