Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1174/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test
Error Message:
Could not find collection:collection2
Stack Trace:
java.lang.AssertionError: Could not find collection:collection2
at
__randomizedtesting.SeedInfo.seed([A3B3E57100AD42F7:2BE7DAABAE512F0F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:140)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:135)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:913)
at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:612)
at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:152)
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.sim.TestClusterStateProvider.testAutoScalingConfig
Error Message:
expected: org.apache.solr.client.solrj.cloud.autoscaling.AutoScalingConfig<{
"cluster-preferences":[{"maximize":"freedisk"}],
"triggers":{".auto_add_replicas":{ "name":".auto_add_replicas",
"event":"nodeLost", "waitFor":30, "actions":[ {
"name":"auto_add_replicas_plan",
"class":"solr.AutoAddReplicasPlanAction"}, {
"name":"execute_plan", "class":"solr.ExecutePlanAction"}],
"enabled":true}}, "listeners":{".auto_add_replicas.system":{
"trigger":".auto_add_replicas", "afterAction":[], "stage":[
"STARTED", "ABORTED", "SUCCEEDED", "FAILED",
"BEFORE_ACTION", "AFTER_ACTION", "IGNORED"],
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
"beforeAction":[]}}, "properties":{}}> but was:
org.apache.solr.client.solrj.cloud.autoscaling.AutoScalingConfig<{
"cluster-preferences":[{"maximize":"freedisk"}],
"triggers":{".auto_add_replicas":{ "name":".auto_add_replicas",
"event":"nodeLost", "waitFor":30, "actions":[ {
"name":"auto_add_replicas_plan",
"class":"solr.AutoAddReplicasPlanAction"}, {
"name":"execute_plan", "class":"solr.ExecutePlanAction"}],
"enabled":true}}, "listeners":{".auto_add_replicas.system":{
"trigger":".auto_add_replicas", "afterAction":[], "stage":[
"STARTED", "ABORTED", "SUCCEEDED", "FAILED",
"BEFORE_ACTION", "AFTER_ACTION", "IGNORED"],
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
"beforeAction":[]}}, "properties":{}}>
Stack Trace:
java.lang.AssertionError: expected:
org.apache.solr.client.solrj.cloud.autoscaling.AutoScalingConfig<{
"cluster-preferences":[{"maximize":"freedisk"}],
"triggers":{".auto_add_replicas":{
"name":".auto_add_replicas",
"event":"nodeLost",
"waitFor":30,
"actions":[
{
"name":"auto_add_replicas_plan",
"class":"solr.AutoAddReplicasPlanAction"},
{
"name":"execute_plan",
"class":"solr.ExecutePlanAction"}],
"enabled":true}},
"listeners":{".auto_add_replicas.system":{
"trigger":".auto_add_replicas",
"afterAction":[],
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED",
"BEFORE_ACTION",
"AFTER_ACTION",
"IGNORED"],
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
"beforeAction":[]}},
"properties":{}}> but was:
org.apache.solr.client.solrj.cloud.autoscaling.AutoScalingConfig<{
"cluster-preferences":[{"maximize":"freedisk"}],
"triggers":{".auto_add_replicas":{
"name":".auto_add_replicas",
"event":"nodeLost",
"waitFor":30,
"actions":[
{
"name":"auto_add_replicas_plan",
"class":"solr.AutoAddReplicasPlanAction"},
{
"name":"execute_plan",
"class":"solr.ExecutePlanAction"}],
"enabled":true}},
"listeners":{".auto_add_replicas.system":{
"trigger":".auto_add_replicas",
"afterAction":[],
"stage":[
"STARTED",
"ABORTED",
"SUCCEEDED",
"FAILED",
"BEFORE_ACTION",
"AFTER_ACTION",
"IGNORED"],
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
"beforeAction":[]}},
"properties":{}}>
at
__randomizedtesting.SeedInfo.seed([A3B3E57100AD42F7:9C3BE6D917FBB210]: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:147)
at
org.apache.solr.cloud.autoscaling.sim.TestClusterStateProvider.testAutoScalingConfig(TestClusterStateProvider.java:214)
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 13025 lines...]
[junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest
[junit4] 2> 1346481 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[A3B3E57100AD42F7]-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/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/init-core-data-001
[junit4] 2> 1346482 WARN
(SUITE-FullSolrCloudDistribCmdsTest-seed#[A3B3E57100AD42F7]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 1346482 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[A3B3E57100AD42F7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1346483 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[A3B3E57100AD42F7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 1346483 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[A3B3E57100AD42F7]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /r_/
[junit4] 2> 1346485 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1346485 INFO (Thread-2742) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1346485 INFO (Thread-2742) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1346486 ERROR (Thread-2742) [ ] 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> 1346585 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.ZkTestServer start zk server on port:39499
[junit4] 2> 1346587 INFO (zkConnectionManagerCallback-2412-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1346588 INFO (zkConnectionManagerCallback-2414-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1346590 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1346591 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 1346592 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1346592 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1346593 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1346593 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1346594 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1346594 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1346595 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1346595 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1346596 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1346596 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 1346688 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1346689 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6852b148{/r_,null,AVAILABLE}
[junit4] 2> 1346690 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4f0ba5db{HTTP/1.1,[http/1.1]}{127.0.0.1:34945}
[junit4] 2> 1346690 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server Started @1348925ms
[junit4] 2> 1346690 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/tempDir-001/control/data,
hostContext=/r_, hostPort=34945,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/control-001/cores}
[junit4] 2> 1346690 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1346690 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 1346690 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1346690 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1346690 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-13T22:23:52.096Z
[junit4] 2> 1346691 INFO (zkConnectionManagerCallback-2416-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1346692 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1346692 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/control-001/solr.xml
[junit4] 2> 1346694 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1346694 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1346695 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1346697 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39499/solr
[junit4] 2> 1346698 INFO (zkConnectionManagerCallback-2420-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1346699 INFO
(zkConnectionManagerCallback-2422-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1346725 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:34945_r_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1346726 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:34945_r_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:34945_r_
[junit4] 2> 1346726 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:34945_r_ ] o.a.s.c.Overseer Overseer
(id=99344857949208580-127.0.0.1:34945_r_-n_0000000000) starting
[junit4] 2> 1346729 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:34945_r_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34945_r_
[junit4] 2> 1346731 INFO
(zkCallback-2421-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1346786 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:34945_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1346791 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:34945_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1346791 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:34945_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1346792 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:34945_r_ ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/control-001/cores
[junit4] 2> 1346805 INFO (zkConnectionManagerCallback-2428-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1346805 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1346805 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39499/solr ready
[junit4] 2> 1346806 INFO (qtp446119897-11469) [n:127.0.0.1:34945_r_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:34945_r_&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1346807 INFO
(OverseerThreadFactory-5065-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.CreateCollectionCmd Create collection
control_collection
[junit4] 2> 1346910 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1346910 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1347012 INFO
(zkCallback-2421-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1347924 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1347931 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 1348124 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1348132 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1'
using configuration from collection control_collection, trusted=true
[junit4] 2> 1348133 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.replica_n1' (registry
'solr.core.control_collection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1348133 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1348133 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore
at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1348135 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4953409321376111]
[junit4] 2> 1348137 WARN (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 1348174 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1348174 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1348176 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1348176 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1348177 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.849235567758425]
[junit4] 2> 1348177 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2f8a6a51[control_collection_shard1_replica_n1] main]
[junit4] 2> 1348196 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1348197 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1348197 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1348198 INFO
(searcherExecutor-5068-thread-1-processing-n:127.0.0.1:34945_r_
x:control_collection_shard1_replica_n1 s:shard1 c:control_collection)
[n:127.0.0.1:34945_r_ c:control_collection s:shard1
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore
[control_collection_shard1_replica_n1] Registered new searcher
Searcher@2f8a6a51[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1348198 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1589517728983547904
[junit4] 2> 1348201 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1348201 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1348201 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:34945/r_/control_collection_shard1_replica_n1/
[junit4] 2> 1348201 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1348201 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy
http://127.0.0.1:34945/r_/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1348201 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1348202 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:34945/r_/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1348303 INFO
(zkCallback-2421-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1348353 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1348354 INFO (qtp446119897-11471) [n:127.0.0.1:34945_r_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1444
[junit4] 2> 1348356 INFO (qtp446119897-11469) [n:127.0.0.1:34945_r_ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1348454 INFO
(zkCallback-2421-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1348808 INFO
(OverseerCollectionConfigSetProcessor-99344857949208580-127.0.0.1:34945_r_-n_0000000000)
[n:127.0.0.1:34945_r_ ] 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> 1349356 INFO (qtp446119897-11469) [n:127.0.0.1:34945_r_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:34945_r_&wt=javabin&version=2}
status=0 QTime=2549
[junit4] 2> 1349359 INFO (zkConnectionManagerCallback-2432-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1349359 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1349360 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39499/solr ready
[junit4] 2> 1349360 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1349360 INFO (qtp446119897-11469) [n:127.0.0.1:34945_r_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=3&createNodeSet=&stateFormat=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1349362 INFO
(OverseerThreadFactory-5065-thread-2-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.CreateCollectionCmd Create collection
collection1
[junit4] 2> 1349362 WARN
(OverseerThreadFactory-5065-thread-2-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.CreateCollectionCmd It is unusual to create
a collection (collection1) without cores.
[junit4] 2> 1349564 INFO (qtp446119897-11469) [n:127.0.0.1:34945_r_ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1349564 INFO (qtp446119897-11469) [n:127.0.0.1:34945_r_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=3&createNodeSet=&stateFormat=1&wt=javabin&version=2}
status=0 QTime=203
[junit4] 2> 1349621 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-1-001
of type NRT
[junit4] 2> 1349621 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1349621 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1d58c3f0{/r_,null,AVAILABLE}
[junit4] 2> 1349622 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@460fc194{HTTP/1.1,[http/1.1]}{127.0.0.1:39359}
[junit4] 2> 1349622 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server Started @1351857ms
[junit4] 2> 1349622 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/r_, hostPort=39359,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-1-001/cores}
[junit4] 2> 1349622 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1349622 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 1349622 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1349622 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1349622 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-13T22:23:55.028Z
[junit4] 2> 1349625 INFO (zkConnectionManagerCallback-2434-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1349626 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1349626 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-1-001/solr.xml
[junit4] 2> 1349630 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1349630 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1349630 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1349635 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39499/solr
[junit4] 2> 1349636 INFO (zkConnectionManagerCallback-2438-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1349637 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 0x160f19f38530008, 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> 1349641 INFO
(zkConnectionManagerCallback-2440-thread-1-processing-n:127.0.0.1:39359_r_)
[n:127.0.0.1:39359_r_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1349644 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:39359_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1349645 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:39359_r_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1349650 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:39359_r_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39359_r_
[junit4] 2> 1349651 INFO
(zkCallback-2421-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1349651 INFO (zkCallback-2431-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1349654 INFO
(zkCallback-2439-thread-1-processing-n:127.0.0.1:39359_r_)
[n:127.0.0.1:39359_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1349773 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:39359_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1349779 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:39359_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1349780 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:39359_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1349781 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:39359_r_ ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-1-001/cores
[junit4] 2> 1349797 INFO (qtp446119897-11470) [n:127.0.0.1:34945_r_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:39359_r_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1349799 INFO
(OverseerCollectionConfigSetProcessor-99344857949208580-127.0.0.1:34945_r_-n_0000000000)
[n:127.0.0.1:34945_r_ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1349799 INFO
(OverseerThreadFactory-5065-thread-3-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:39359_r_ for creating new replica
[junit4] 2> 1349800 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n61&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1349801 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1350812 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1350822 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.s.IndexSchema
[collection1_shard2_replica_n61] Schema name=test
[junit4] 2> 1350869 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1350875 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n61' using configuration from
collection collection1, trusted=true
[junit4] 2> 1350875 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_n61' (registry
'solr.core.collection1.shard2.replica_n61') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1350876 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1350876 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.SolrCore
[[collection1_shard2_replica_n61] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-1-001/cores/collection1_shard2_replica_n61],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-1-001/cores/collection1_shard2_replica_n61/data/]
[junit4] 2> 1350878 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4953409321376111]
[junit4] 2> 1350879 WARN (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 1350908 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1350908 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1350909 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1350909 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1350910 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.849235567758425]
[junit4] 2> 1350910 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@347a348d[collection1_shard2_replica_n61] main]
[junit4] 2> 1350911 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1350911 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1350911 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1350912 INFO
(searcherExecutor-5079-thread-1-processing-n:127.0.0.1:39359_r_
x:collection1_shard2_replica_n61 s:shard2 c:collection1) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.SolrCore
[collection1_shard2_replica_n61] Registered new searcher
Searcher@347a348d[collection1_shard2_replica_n61]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1350912 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1589517731829383168
[junit4] 2> 1350916 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1350916 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1350916 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:39359/r_/collection1_shard2_replica_n61/
[junit4] 2> 1350916 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1350916 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.SyncStrategy
http://127.0.0.1:39359/r_/collection1_shard2_replica_n61/ has no replicas
[junit4] 2> 1350916 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1350917 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:39359/r_/collection1_shard2_replica_n61/ shard2
[junit4] 2> 1351068 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1351074 INFO (qtp757969246-11518) [n:127.0.0.1:39359_r_
c:collection1 s:shard2 x:collection1_shard2_replica_n61] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n61&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1273
[junit4] 2> 1351075 INFO (qtp446119897-11470) [n:127.0.0.1:34945_r_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:39359_r_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
status=0 QTime=1277
[junit4] 2> 1351169 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-2-001
of type NRT
[junit4] 2> 1351170 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1351170 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2e86854e{/r_,null,AVAILABLE}
[junit4] 2> 1351171 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@40d77f91{HTTP/1.1,[http/1.1]}{127.0.0.1:35731}
[junit4] 2> 1351171 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server Started @1353406ms
[junit4] 2> 1351171 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/tempDir-001/jetty2,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/r_, hostPort=35731,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-2-001/cores}
[junit4] 2> 1351171 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1351171 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 1351171 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1351171 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1351171 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-13T22:23:56.577Z
[junit4] 2> 1351172 INFO (zkConnectionManagerCallback-2444-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1351173 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1351173 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-2-001/solr.xml
[junit4] 2> 1351176 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1351176 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1351176 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1351178 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39499/solr
[junit4] 2> 1351179 INFO (zkConnectionManagerCallback-2448-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1351180 INFO
(zkConnectionManagerCallback-2450-thread-1-processing-n:127.0.0.1:35731_r_)
[n:127.0.0.1:35731_r_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1351183 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:35731_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1351183 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:35731_r_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1351184 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:35731_r_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35731_r_
[junit4] 2> 1351185 INFO
(zkCallback-2439-thread-1-processing-n:127.0.0.1:39359_r_)
[n:127.0.0.1:39359_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1351185 INFO (zkCallback-2431-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1351185 INFO
(zkCallback-2421-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1351186 INFO
(zkCallback-2449-thread-1-processing-n:127.0.0.1:35731_r_)
[n:127.0.0.1:35731_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1351224 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:35731_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1351273 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:35731_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1351274 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:35731_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1351275 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:35731_r_ ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-2-001/cores
[junit4] 2> 1351297 INFO (qtp446119897-11465) [n:127.0.0.1:34945_r_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:35731_r_&action=ADDREPLICA&collection=collection1&shard=shard3&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1351298 INFO
(OverseerCollectionConfigSetProcessor-99344857949208580-127.0.0.1:34945_r_-n_0000000000)
[n:127.0.0.1:34945_r_ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1351298 INFO
(OverseerThreadFactory-5065-thread-4-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:35731_r_ for creating new replica
[junit4] 2> 1351299 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard3_replica_n63&action=CREATE&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1351299 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1352309 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1352316 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.s.IndexSchema
[collection1_shard3_replica_n63] Schema name=test
[junit4] 2> 1352363 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1352369 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard3_replica_n63' using configuration from
collection collection1, trusted=true
[junit4] 2> 1352370 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard3.replica_n63' (registry
'solr.core.collection1.shard3.replica_n63') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1352370 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1352370 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.SolrCore
[[collection1_shard3_replica_n63] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-2-001/cores/collection1_shard3_replica_n63],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-2-001/cores/collection1_shard3_replica_n63/data/]
[junit4] 2> 1352371 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4953409321376111]
[junit4] 2> 1352373 WARN (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 1352403 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1352403 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1352404 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1352404 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1352405 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.849235567758425]
[junit4] 2> 1352405 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@50018a01[collection1_shard3_replica_n63] main]
[junit4] 2> 1352406 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1352407 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1352407 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1352408 INFO
(searcherExecutor-5090-thread-1-processing-n:127.0.0.1:35731_r_
x:collection1_shard3_replica_n63 s:shard3 c:collection1) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.SolrCore
[collection1_shard3_replica_n63] Registered new searcher
Searcher@50018a01[collection1_shard3_replica_n63]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1352408 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1589517733398052864
[junit4] 2> 1352411 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1352411 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1352411 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:35731/r_/collection1_shard3_replica_n63/
[junit4] 2> 1352411 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1352411 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.SyncStrategy
http://127.0.0.1:35731/r_/collection1_shard3_replica_n63/ has no replicas
[junit4] 2> 1352411 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1352412 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:35731/r_/collection1_shard3_replica_n63/ shard3
[junit4] 2> 1352563 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1352569 INFO (qtp244516907-11549) [n:127.0.0.1:35731_r_
c:collection1 s:shard3 x:collection1_shard3_replica_n63] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard3_replica_n63&action=CREATE&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1269
[junit4] 2> 1352570 INFO (qtp446119897-11465) [n:127.0.0.1:34945_r_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:35731_r_&action=ADDREPLICA&collection=collection1&shard=shard3&type=NRT&wt=javabin&version=2}
status=0 QTime=1273
[junit4] 2> 1352661 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-3-001
of type NRT
[junit4] 2> 1352661 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1352664 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@733adfae{/r_,null,AVAILABLE}
[junit4] 2> 1352664 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7f42e510{HTTP/1.1,[http/1.1]}{127.0.0.1:33589}
[junit4] 2> 1352664 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server Started @1354900ms
[junit4] 2> 1352664 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/r_, hostPort=33589,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-3-001/cores}
[junit4] 2> 1352665 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1352665 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 1352665 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1352665 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1352665 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-13T22:23:58.071Z
[junit4] 2> 1352666 INFO (zkConnectionManagerCallback-2454-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1352666 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1352666 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-3-001/solr.xml
[junit4] 2> 1352670 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 1352670 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1352670 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1352673 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39499/solr
[junit4] 2> 1352674 INFO (zkConnectionManagerCallback-2458-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1352675 INFO
(zkConnectionManagerCallback-2460-thread-1-processing-n:127.0.0.1:33589_r_)
[n:127.0.0.1:33589_r_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1352678 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:33589_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1352679 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:33589_r_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1352680 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:33589_r_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33589_r_
[junit4] 2> 1352680 INFO
(zkCallback-2439-thread-1-processing-n:127.0.0.1:39359_r_)
[n:127.0.0.1:39359_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1352680 INFO (zkCallback-2431-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1352680 INFO
(zkCallback-2449-thread-1-processing-n:127.0.0.1:35731_r_)
[n:127.0.0.1:35731_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1352680 INFO
(zkCallback-2421-thread-1-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1352680 INFO
(zkCallback-2459-thread-1-processing-n:127.0.0.1:33589_r_)
[n:127.0.0.1:33589_r_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1352768 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:33589_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1352775 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:33589_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1352775 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:33589_r_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1352776 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7])
[n:127.0.0.1:33589_r_ ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-3-001/cores
[junit4] 2> 1352804 INFO (qtp1140656416-11580) [n:127.0.0.1:33589_r_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:33589_r_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1352805 INFO
(OverseerCollectionConfigSetProcessor-99344857949208580-127.0.0.1:34945_r_-n_0000000000)
[n:127.0.0.1:34945_r_ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1352805 INFO
(OverseerThreadFactory-5065-thread-5-processing-n:127.0.0.1:34945_r_)
[n:127.0.0.1:34945_r_ ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:33589_r_ for creating new replica
[junit4] 2> 1352806 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n65&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1352806 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1353834 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.3.0
[junit4] 2> 1353842 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.s.IndexSchema
[collection1_shard1_replica_n65] Schema name=test
[junit4] 2> 1353897 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1353904 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_n65' using configuration from
collection collection1, trusted=true
[junit4] 2> 1353904 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_n65' (registry
'solr.core.collection1.shard1.replica_n65') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@61102e3f
[junit4] 2> 1353904 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1353904 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.SolrCore
[[collection1_shard1_replica_n65] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-3-001/cores/collection1_shard1_replica_n65],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-3-001/cores/collection1_shard1_replica_n65/data/]
[junit4] 2> 1353906 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4953409321376111]
[junit4] 2> 1353908 WARN (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 1353933 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1353933 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1353934 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1353934 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1353934 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.849235567758425]
[junit4] 2> 1353935 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@bda75da[collection1_shard1_replica_n65] main]
[junit4] 2> 1353935 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1353935 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1353936 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1353936 INFO
(searcherExecutor-5101-thread-1-processing-n:127.0.0.1:33589_r_
x:collection1_shard1_replica_n65 s:shard1 c:collection1) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.SolrCore
[collection1_shard1_replica_n65] Registered new searcher
Searcher@bda75da[collection1_shard1_replica_n65]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1353936 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1589517735000276992
[junit4] 2> 1353938 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1353939 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1353939 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:33589/r_/collection1_shard1_replica_n65/
[junit4] 2> 1353939 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1353939 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.SyncStrategy
http://127.0.0.1:33589/r_/collection1_shard1_replica_n65/ has no replicas
[junit4] 2> 1353939 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1353940 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:33589/r_/collection1_shard1_replica_n65/ shard1
[junit4] 2> 1354090 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1354091 INFO (qtp1140656416-11582) [n:127.0.0.1:33589_r_
c:collection1 s:shard1 x:collection1_shard1_replica_n65] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n65&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1285
[junit4] 2> 1354092 INFO (qtp1140656416-11580) [n:127.0.0.1:33589_r_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:33589_r_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=1288
[junit4] 2> 1354159 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-4-001
of type NRT
[junit4] 2> 1354159 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1354160 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2d8ad17c{/r_,null,AVAILABLE}
[junit4] 2> 1354160 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@14803ef6{HTTP/1.1,[http/1.1]}{127.0.0.1:39361}
[junit4] 2> 1354160 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.e.j.s.Server Started @1356396ms
[junit4] 2> 1354160 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/tempDir-001/jetty4,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/r_, hostPort=39361,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.FullSolrCloudDistribCmdsTest_A3B3E57100AD42F7-001/shard-4-001/cores}
[junit4] 2> 1354161 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1354161 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[A3B3E57100AD42F7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _
[...truncated too long message...]
G SolrCore org.apache.solr.core.SolrCore@2ce282b9
[junit4] 2> 2876577 INFO (coreCloseExecutor-6527-thread-1)
[n:127.0.0.1:43825_solr c:.system s:shard1 r:core_node3
x:.system_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters
for registry=solr.core..system.shard1.replica_n1, tag=753042105
[junit4] 2> 2876577 INFO (coreCloseExecutor-6527-thread-1)
[n:127.0.0.1:43825_solr c:.system s:shard1 r:core_node3
x:.system_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@210efea5: rootName =
solr_43825, domain = solr.core..system.shard1.replica_n1, service url = null,
agent id = null] for registry solr.core..system.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@357cf3e3
[junit4] 2> 2876577 INFO (jetty-closer-7744-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:43165_solr
[junit4] 2> 2876577 INFO (jetty-closer-7744-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 2876578 INFO
(zkCallback-7767-thread-3-processing-n:127.0.0.1:43165_solr)
[n:127.0.0.1:43165_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 2876578 INFO
(zkCallback-7769-thread-3-processing-n:127.0.0.1:43825_solr)
[n:127.0.0.1:43825_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 2876578 INFO (coreCloseExecutor-6528-thread-1)
[n:127.0.0.1:43165_solr c:.system s:shard1 r:core_node5
x:.system_shard1_replica_n2] o.a.s.c.SolrCore [.system_shard1_replica_n2]
CLOSING SolrCore org.apache.solr.core.SolrCore@5483ed10
[junit4] 2> 2876578 INFO (coreCloseExecutor-6528-thread-1)
[n:127.0.0.1:43165_solr c:.system s:shard1 r:core_node5
x:.system_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters
for registry=solr.core..system.shard1.replica_n2, tag=1417932048
[junit4] 2> 2876578 INFO (coreCloseExecutor-6528-thread-1)
[n:127.0.0.1:43165_solr c:.system s:shard1 r:core_node5
x:.system_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@39529ffe: rootName =
solr_43165, domain = solr.core..system.shard1.replica_n2, service url = null,
agent id = null] for registry solr.core..system.shard1.replica_n2 /
com.codahale.metrics.MetricRegistry@d29faa7
[junit4] 2> 2876585 INFO (coreCloseExecutor-6528-thread-1)
[n:127.0.0.1:43165_solr c:.system s:shard1 r:core_node5
x:.system_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters
for registry=solr.collection..system.shard1.leader, tag=1417932048
[junit4] 2> 2876585 INFO (jetty-closer-7744-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=99344957690544136-127.0.0.1:43165_solr-n_0000000001) closing
[junit4] 2> 2876585 INFO
(OverseerStateUpdate-99344957690544136-127.0.0.1:43165_solr-n_0000000001)
[n:127.0.0.1:43165_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:43165_solr
[junit4] 2> 2876586 WARN
(OverseerAutoScalingTriggerThread-99344957690544136-127.0.0.1:43165_solr-n_0000000001)
[n:127.0.0.1:43165_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2876587 INFO
(zkCallback-7769-thread-3-processing-n:127.0.0.1:43825_solr)
[n:127.0.0.1:43825_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:43825_solr
[junit4] 2> 2876588 INFO (jetty-closer-7744-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6e4f5c3{/solr,null,UNAVAILABLE}
[junit4] 2> 2876589 INFO (coreCloseExecutor-6527-thread-1)
[n:127.0.0.1:43825_solr c:.system s:shard1 r:core_node3
x:.system_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters
for registry=solr.collection..system.shard1.leader, tag=753042105
[junit4] 2> 2876591 INFO (jetty-closer-7744-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@550742b6{/solr,null,UNAVAILABLE}
[junit4] 2> 2876591 ERROR
(SUITE-TestClusterStateProvider-seed#[A3B3E57100AD42F7]-worker) [ ]
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> 2876592 INFO
(SUITE-TestClusterStateProvider-seed#[A3B3E57100AD42F7]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:45583 45583
[junit4] 2> 2884132 INFO (Thread-3009) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:45583 45583
[junit4] 2> 2884133 WARN (Thread-3009) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/configs/.system
[junit4] 2> 3 /solr/configs/.system/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/collections/.system/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestClusterStateProvider_A3B3E57100AD42F7-001
[junit4] 2> NOTE: test params are:
codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
chunkSize=20056, maxDocsPerChunk=800, blockSize=9),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY,
chunkSize=20056, blockSize=9)), sim=RandomSimilarity(queryNorm=true): {},
locale=pt-PT, timezone=Europe/Dublin
[junit4] 2> NOTE: Linux 4.13.0-26-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=8,threads=1,free=198276368,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [ReplaceNodeTest,
TestSerializedLuceneMatchVersion, CoreAdminCreateDiscoverTest,
TestBinaryResponseWriter, SolrIndexConfigTest, TestSolrCLIRunExample,
DistributedFacetPivotLongTailTest, TestImpersonationWithHadoopAuth, ZkCLITest,
CdcrUpdateLogTest, BinaryUpdateRequestHandlerTest, AnalyticsMergeStrategyTest,
TestLegacyFieldCache, TestLuceneMatchVersion, LegacyCloudClusterPropTest,
TestStressLucene, TestExclusionRuleCollectionAccess, NodeAddedTriggerTest,
DocExpirationUpdateProcessorFactoryTest, TestBackupRepositoryFactory,
ShardRoutingTest, MetricsConfigTest, TestCloudRecovery,
LeaderFailureAfterFreshStartTest, MoreLikeThisHandlerTest, DateRangeFieldTest,
BadIndexSchemaTest, HLLSerializationTest, TestSubQueryTransformer,
LukeRequestHandlerTest, DocumentAnalysisRequestHandlerTest, TestMacros,
TestReloadDeadlock, SolrIndexMetricsTest, TestXIncludeConfig,
TestReloadAndDeleteDocs, TestCursorMarkWithoutUniqueKey,
WrapperMergePolicyFactoryTest, DistribDocExpirationUpdateProcessorTest,
ShowFileRequestHandlerTest, TestHalfAndHalfDocValues,
TestSolrCloudWithKerberosAlt, BooleanFieldTest, PingRequestHandlerTest,
HttpSolrCallGetCoreTest, TestDocumentBuilder, TestExecutePlanAction,
TestBulkSchemaAPI, SolrIndexSplitterTest, NumericFieldsTest,
TestFileDictionaryLookup, OutputWriterTest, TestSchemaResource,
TestRandomFlRTGCloud, TestSchemalessBufferedUpdates,
TestPayloadScoreQParserPlugin, SchemaVersionSpecificBehaviorTest,
PolyFieldTest, TestManagedResource, RegexBoostProcessorTest,
TestConfigSetProperties, TlogReplayBufferedWhileIndexingTest,
ClusterStateUpdateTest, PreAnalyzedUpdateProcessorTest, TestNoOpRegenerator,
TestFieldSortValues, SuggesterFSTTest, TestBlobHandler, BJQParserTest,
ConvertedLegacyTest, TestWriterPerf, OverseerRolesTest, TestFieldCache,
NotRequiredUniqueKeyTest, TestSizeLimitedDistributedMap,
CollectionsAPIDistributedZkTest, TestMultiValuedNumericRangeQuery,
ManagedSchemaRoundRobinCloudTest, SolrSlf4jReporterTest,
TestDeleteCollectionOnDownNodes, QueryEqualityTest, HighlighterConfigTest,
TestSimDistributedQueue, TestMaxTokenLenTokenizer, TestStressInPlaceUpdates,
TestLockTree, SampleTest, TestFastWriter, RollingRestartTest, TestJsonRequest,
TermVectorComponentDistributedTest, SecurityConfHandlerTest, DOMUtilTest,
TestTrackingShardHandlerFactory, TestSmileRequest, HdfsSyncSliceTest,
TestInfoStreamLogging, ChaosMonkeySafeLeaderTest, TestAnalyzedSuggestions,
AssignTest, ReplicaListTransformerTest,
ClassificationUpdateProcessorIntegrationTest, TestRemoteStreaming,
SolrMetricReporterTest, ShardRoutingCustomTest,
TestComplexPhraseLeadingWildcard, TestNamedUpdateProcessors, TestFastLRUCache,
DebugComponentTest, HdfsRecoverLeaseTest, BlockCacheTest,
DistributedTermsComponentTest, BlobRepositoryCloudTest,
RegexBytesRefFilterTest, CdcrReplicationHandlerTest,
TestCloudJSONFacetJoinDomain, DistributedFacetPivotWhiteBoxTest,
TestUninvertingReader, TestCoreAdminApis,
DistributedFacetSimpleRefinementLongTailTest, TestLegacyFieldReuse,
SystemLogListenerTest, BasicDistributedZkTest, SolrXmlInZkTest,
TestClusterProperties, TestCollectionAPI, TestDistribDocBasedVersion,
TestLeaderElectionWithEmptyReplica, TestLeaderElectionZkExpiry,
TestMiniSolrCloudClusterSSL, TestRandomRequestDistribution,
TestReplicaProperties, TestSSLRandomization,
TestSolrCloudWithSecureImpersonation, TestStressLiveNodes, TestTlogReplica,
AutoAddReplicasIntegrationTest, NodeLostTriggerTest, TestClusterStateProvider]
[junit4] Completed [571/766 (2!)] on J1 in 15.77s, 2 tests, 1 failure <<<
FAILURES!
[...truncated 48798 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
