Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/858/
2 tests failed.
FAILED:
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR
Error Message:
There are still nodes recoverying - waited for 330 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 330
seconds
at
__randomizedtesting.SeedInfo.seed([344D7521C48CA104:EAC71B5CFF2EC3F7]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:170)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:131)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:126)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:830)
at
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR(LeaderInitiatedRecoveryOnShardRestartTest.java:109)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java: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:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=195371, name=collection2,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=195371, name=collection2, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:51590/np: collection already exists:
awholynewstresscollection_collection2_0
at __randomizedtesting.SeedInfo.seed([344D7521C48CA104]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1573)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1594)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:876)
Build Log:
[...truncated 10769 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/init-core-data-001
[junit4] 2> 2147220 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[344D7521C48CA104]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
[junit4] 2> 2147220 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[344D7521C48CA104]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /np/
[junit4] 2> 2147225 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2147225 INFO (Thread-188436) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2147225 INFO (Thread-188436) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2147325 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.ZkTestServer start zk server on port:53251
[junit4] 2> 2147326 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2147326 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2147336 INFO (zkCallback-1163-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2ac6506a
name:ZooKeeperConnection Watcher:127.0.0.1:53251 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2147336 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2147337 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2147337 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2147340 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2147341 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2147347 INFO (zkCallback-1164-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@54f324ee
name:ZooKeeperConnection Watcher:127.0.0.1:53251/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2147348 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2147348 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2147348 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 2147351 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 2147354 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 2147356 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 2147357 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 2147357 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2147361 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 2147361 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 2147363 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2147363 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2147364 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 2147365 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2147367 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 2147367 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 2147369 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 2147369 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 2147372 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 2147372 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2147374 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2147374 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2147377 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2147377 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2147379 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 2147379 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2147381 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 2147381 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2147383 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2147384 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2147384 INFO (zkCallback-1165-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@88f7e05 name:ZooKeeperConnection
Watcher:127.0.0.1:53251 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 2147385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2147385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2147385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2147386 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2147386 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2147396 INFO (zkCallback-1166-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5d707277
name:ZooKeeperConnection Watcher:127.0.0.1:53251/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2147396 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2147396 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2147396 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf2/solrconfig.xml
[junit4] 2> 2147397 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 2147399 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf2/schema.xml
[junit4] 2> 2147399 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
[junit4] 2> 2147400 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf2/enumsConfig.xml
[junit4] 2> 2147401 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
[junit4] 2> 2147402 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2147402 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2147403 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf2/stopwords.txt
[junit4] 2> 2147404 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
[junit4] 2> 2147414 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf2/protwords.txt
[junit4] 2> 2147414 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
[junit4] 2> 2147416 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf2/currency.xml
[junit4] 2> 2147417 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
[junit4] 2> 2147418 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf2/open-exchange-rates.json
[junit4] 2> 2147418 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 2147420 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 2147420 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 2147423 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf2/old_synonyms.txt
[junit4] 2> 2147424 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 2147426 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf2/synonyms.txt
[junit4] 2> 2147426 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
[junit4] 2> 2147428 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml
to /configs/conf2/elevate.xml
[junit4] 2> 2147428 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
[junit4] 2> 2147836 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1
[junit4] 2> 2147838 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 2147848 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6c40d16c{/np,null,AVAILABLE}
[junit4] 2> 2147850 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@44f7cd0{HTTP/1.1}{127.0.0.1:39783}
[junit4] 2> 2147850 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.e.j.s.Server Started @2150920ms
[junit4] 2> 2147850 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/tempDir-001/control/data,
hostContext=/np, hostPort=39783,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores}
[junit4] 2> 2147850 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 2147850 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001'
[junit4] 2> 2147850 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2147851 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 2147851 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2147852 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2147853 INFO (zkCallback-1167-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@769be3b name:ZooKeeperConnection
Watcher:127.0.0.1:53251/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 2147853 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2147853 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2147856 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2147856 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/solr.xml
[junit4] 2> 2147861 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.CoresLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores
[junit4] 2> 2147861 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1707658841
[junit4] 2> 2147861 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001]
[junit4] 2> 2147861 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/lib
[junit4] 2> 2147861 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections :
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime :
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2147863 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2147863 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2147863 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2147864 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53251/solr
[junit4] 2> 2147864 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 2147864 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2147865 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2147880 INFO (zkCallback-1169-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@18fefdf7
name:ZooKeeperConnection Watcher:127.0.0.1:53251 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2147881 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2147881 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2147888 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 2147902 INFO
(zkCallback-1170-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@19aa6de4
name:ZooKeeperConnection Watcher:127.0.0.1:53251/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2147902 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 2147904 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 2147905 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 2147907 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 2147909 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 2147912 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 2147915 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2147916 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2147918 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2147919 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2147920 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39783_np
[junit4] 2> 2147920 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:39783_np
[junit4] 2> 2147922 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 2147923 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 2147925 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2147926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.LeaderElector Joined leadership election
with path:
/overseer_elect/election/94899495912603654-127.0.0.1:39783_np-n_0000000000
[junit4] 2> 2147926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:39783_np
[junit4] 2> 2147926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/leader
[junit4] 2> 2147928 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.Overseer Overseer
(id=94899495912603654-127.0.0.1:39783_np-n_0000000000) starting
[junit4] 2> 2147930 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 2147937 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 2147937 INFO
(OverseerCollectionConfigSetProcessor-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.OverseerTaskProcessor Process current queue
of overseer operations
[junit4] 2> 2147937 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 2147938 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 2147960 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 2147960 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2147961 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.CoresLocator Looking for core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores
[junit4] 2> 2147962 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.SolrCore Created CoreDescriptor:
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1,
collection=control_collection,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1/,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 2147962 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.CoresLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1/
[junit4] 2> 2147962 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 2147963 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
[junit4] 2> 2147963 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:39783_np ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 2147964 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.ZkController publishing state=down
[junit4] 2> 2147964 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.ZkController numShards not found on descriptor - reading it from system
property
[junit4] 2> 2147965 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:39783/np";,
[junit4] 2> "node_name":"127.0.0.1:39783_np",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2147965 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:39783/np";,
[junit4] 2> "node_name":"127.0.0.1:39783_np",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 2147965 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.ZkController look for our core node name
[junit4] 2> 2147966 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.o.ClusterStateMutator building a new cName:
control_collection
[junit4] 2> 2147966 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2147968 INFO
(zkCallback-1170-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np ] o.a.s.c.c.ZkStateReader A cluster state change:
WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json,
has occurred - updating... (live nodes size: 1)
[junit4] 2> 2148966 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 2148966 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 2148966 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 2148967 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config
from:/collections/control_collection
[junit4] 2> 2148967 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1
specified config exists in ZooKeeper
[junit4] 2> 2148967 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1'
[junit4] 2> 2148968 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2148968 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 2148969 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1] o.a.s.c.Config
loaded config solrconfig.xml with version 0
[junit4] 2> 2148982 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 2148989 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.0.0
[junit4] 2> 2149002 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1] o.a.s.c.Config
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2149013 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2149136 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2149144 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1] o.a.s.s.IndexSchema
default search field in schema is text
[junit4] 2> 2149145 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1] o.a.s.s.IndexSchema
unique key field: id
[junit4] 2> 2149156 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 2149158 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 2149160 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 2149160 WARN
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 2149161 WARN
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 2149161 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 2149161 WARN
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 2149161 WARN
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 2149163 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/configs/conf1',
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
[junit4] 2> 2149163 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 2149163 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2149163 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1],
dataDir=[null]
[junit4] 2> 2149163 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6d88ff27
[junit4] 2> 2149164 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1/data
[junit4] 2> 2149166 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1/data/index/
[junit4] 2> 2149166 WARN
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 2149166 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1/data/index
[junit4] 2> 2149167 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=8, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=26.4736328125,
floorSegmentMB=0.470703125, forceMergeDeletesPctAllowed=20.278990293294196,
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.3242110183618963
[junit4] 2> 2149168 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@2b7b159
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@61b7a0f4),segFN=segments_1,generation=1}
[junit4] 2> 2149168 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 2149178 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
Looking for old index directories to cleanup for core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1/data/
[junit4] 2> 2149178 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.DirectoryFactory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 2149180 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 2149180 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 2149180 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2149180 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 2149181 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2149181 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 2149181 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2149181 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 2149181 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating
implicit default
[junit4] 2> 2149181 WARN
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 2149183 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2149184 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2149184 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2149185 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2149188 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 2149188 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2149189 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2149189 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2149190 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 2149190 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 2149190 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@141c4edb
[junit4] 2> 2149190 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@2b7b159
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@61b7a0f4),segFN=segments_1,generation=1}
[junit4] 2> 2149190 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 2149190 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@190efaea[collection1] main
[junit4] 2> 2149190 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.ZkStateReader Load collection config
from:/collections/control_collection
[junit4] 2> 2149193 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1
specified config exists in ZooKeeper
[junit4] 2> 2149193 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the
RestManager with znodeBase: /configs/conf1
[junit4] 2> 2149193 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2149193 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 2149193 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2149195 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage No data found for znode
/configs/conf1/_rest_managed.json
[junit4] 2> 2149196 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2149196 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 2149196 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2149196 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 2149196 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version
buckets from index
[junit4] 2> 2149196 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket
highest value from index
[junit4] 2> 2149196 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1518391936666304512
[junit4] 2> 2149200 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Took 3.0ms to seed version buckets with highest version
1518391936666304512
[junit4] 2> 2149200 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 2149200 INFO
(searcherExecutor-4288-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@190efaea[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2149201 INFO
(coreLoadExecutor-4287-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 2149214 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
Register replica - core:collection1 address:http://127.0.0.1:39783/np
collection:control_collection shard:shard1
[junit4] 2> 2149216 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2149219 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector
Joined leadership election with path:
/collections/control_collection/leader_elect/shard1/election/94899495912603654-core_node1-n_0000000000
[junit4] 2> 2149219 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
[junit4] 2> 2149221 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 2149222 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2149222 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2149222 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:39783/np/collection1/
[junit4] 2> 2149222 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 2149222 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:39783/np/collection1/ has no replicas
[junit4] 2> 2149223 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 2149225 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 2149226 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:39783/np";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 2149228 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:39783/np/collection1/ shard1
[junit4] 2> 2149328 INFO
(zkCallback-1170-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np ] o.a.s.c.c.ZkStateReader A cluster state change:
WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json,
has occurred - updating... (live nodes size: 1)
[junit4] 2> 2149378 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are http://127.0.0.1:39783/np/collection1/ and leader is
http://127.0.0.1:39783/np/collection1/
[junit4] 2> 2149379 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39783/np
[junit4] 2> 2149379 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 2149379 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 2149379 INFO
(coreZkRegister-4281-thread-1-processing-n:127.0.0.1:39783_np x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39783_np
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 2149380 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:39783/np";,
[junit4] 2> "node_name":"127.0.0.1:39783_np",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 2149381 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:39783/np";,
[junit4] 2> "node_name":"127.0.0.1:39783_np",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 2149465 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2149467 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149482 INFO (zkCallback-1172-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4332d711
name:ZooKeeperConnection Watcher:127.0.0.1:53251/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2149483 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149483 INFO
(zkCallback-1170-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np ] o.a.s.c.c.ZkStateReader A cluster state change:
WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json,
has occurred - updating... (live nodes size: 1)
[junit4] 2> 2149483 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2149483 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2149485 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 2149485 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 2149485 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2149503 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149503 INFO (zkCallback-1173-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2e8808df
name:ZooKeeperConnection Watcher:127.0.0.1:53251/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2149503 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149503 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2149505 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 3
[junit4] 2> 2149505 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.o.ClusterStateMutator building a new cName:
collection1
[junit4] 2> 2149612 INFO
(OverseerStateUpdate-94899495912603654-127.0.0.1:39783_np-n_0000000000)
[n:127.0.0.1:39783_np ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/collection1/state.json
[junit4] 2> 2149976 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001/cores/collection1
[junit4] 2> 2149978 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001
[junit4] 2> 2149979 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 2149983 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1d21ba4b{/np,null,AVAILABLE}
[junit4] 2> 2149984 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@7f1a95df{HTTP/1.1}{127.0.0.1:56752}
[junit4] 2> 2149984 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.e.j.s.Server Started @2153054ms
[junit4] 2> 2149984 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/np, hostPort=56752,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001/cores}
[junit4] 2> 2149984 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 2149984 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001'
[junit4] 2> 2149984 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2149984 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 2149985 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2149986 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149987 INFO (zkCallback-1174-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@72e4ab91
name:ZooKeeperConnection Watcher:127.0.0.1:53251/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2149987 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2149987 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2149989 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2149989 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001/solr.xml
[junit4] 2> 2149994 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.CoresLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001/cores
[junit4] 2> 2149994 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.CoreContainer New CoreContainer 910798208
[junit4] 2> 2149994 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001]
[junit4] 2> 2149994 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001/lib
[junit4] 2> 2149994 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections :
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime :
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2149995 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2149996 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2149996 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2149996 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53251/solr
[junit4] 2> 2149996 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 2149996 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2149997 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2149998 INFO (zkCallback-1176-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4c13a947
name:ZooKeeperConnection Watcher:127.0.0.1:53251 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2150002 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2150002 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2150005 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 2150014 INFO
(zkCallback-1177-thread-1-processing-n:127.0.0.1:56752_np)
[n:127.0.0.1:56752_np ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5ea59c5b
name:ZooKeeperConnection Watcher:127.0.0.1:53251/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2150014 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 2150019 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 2151024 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56752_np
[junit4] 2> 2151024 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:56752_np
[junit4] 2> 2151025 INFO
(zkCallback-1170-thread-1-processing-n:127.0.0.1:39783_np)
[n:127.0.0.1:39783_np ] o.a.s.c.c.ZkStateReader A live node change:
WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has
occurred - updating... (live nodes size: 1)
[junit4] 2> 2151025 INFO (zkCallback-1172-thread-1) [ ]
o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live
nodes size: 1)
[junit4] 2> 2151026 INFO
(zkCallback-1177-thread-1-processing-n:127.0.0.1:56752_np)
[n:127.0.0.1:56752_np ] o.a.s.c.c.ZkStateReader A live node change:
WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has
occurred - updating... (live nodes size: 1)
[junit4] 2> 2151028 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2151028 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.LeaderElector Joined leadership election
with path:
/overseer_elect/election/94899495912603659-127.0.0.1:56752_np-n_0000000001
[junit4] 2> 2151029 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/94899495912603654-127.0.0.1:39783_np-n_0000000000 to
know if I could be the leader
[junit4] 2> 2151052 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 2151052 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2151052 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.CoresLocator Looking for core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_344D7521C48CA104-001/shard-1-001/cores
[junit4] 2> 2151053 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[344D7521C48CA104])
[n:127.0.0.1:56752_np ] o.a.s.c.SolrCore Created CoreDescriptor:
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml,
[...truncated too long message...]
unit4] 2> at
org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:255)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:620)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:426)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:58)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:388)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:269)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3995838 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[344D7521C48CA104])
[ ] o.e.j.s.ServerConnector Stopped
ServerConnector@10d0e280{HTTP/1.1}{127.0.0.1:47743}
[junit4] 2> 3995840 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[344D7521C48CA104])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5a17fa15{/mp_ua/as,null,UNAVAILABLE}
[junit4] 2> 3995850 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[344D7521C48CA104])
[n:127.0.0.1:47743_mp_ua%2Fas c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33459 33459
[junit4] 2> 3995968 INFO (Thread-190345) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:33459 33459
[junit4] 2> 3995971 WARN (Thread-190345) [ ] o.a.s.c.ZkTestServer
Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 11 /solr/aliases.json
[junit4] 2> 10 /solr/security.json
[junit4] 2> 10 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/collections/all_in_lir/state.json
[junit4] 2> 17 /solr/collections/collection1/state.json
[junit4] 2> 11 /solr/clusterstate.json
[junit4] 2> 6
/solr/overseer_elect/election/94899589570166788-127.0.0.1:52318_mp_ua%2Fas-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 11 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=LeaderInitiatedRecoveryOnShardRestartTest
-Dtests.method=testRestartWithAllInLIR -Dtests.seed=344D7521C48CA104
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=ko -Dtests.timezone=Europe/Bucharest -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 420s J0 |
LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes
recoverying - waited for 330 seconds
[junit4] > at
__randomizedtesting.SeedInfo.seed([344D7521C48CA104:EAC71B5CFF2EC3F7]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:170)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:131)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:126)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:830)
[junit4] > at
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR(LeaderInitiatedRecoveryOnShardRestartTest.java:109)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3995980 INFO
(SUITE-LeaderInitiatedRecoveryOnShardRestartTest-seed#[344D7521C48CA104]-worker)
[n:127.0.0.1:47743_mp_ua%2Fas c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_344D7521C48CA104-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {},
docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {},
locale=ko, timezone=Europe/Bucharest
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_66 (64-bit)/cpus=4,threads=1,free=356625768,total=509083648
[junit4] 2> NOTE: All tests run in this JVM: [ResponseHeaderTest,
ResourceLoaderTest, TestLuceneMatchVersion, TestLRUCache,
TestMergePolicyConfig, TestSmileRequest, CloudExitableDirectoryReaderTest,
DateRangeFieldTest, HdfsWriteToMultipleCollectionsTest,
TestSha256AuthenticationProvider, HdfsDirectoryFactoryTest,
AlternateDirectoryTest, TestHashQParserPlugin, DocValuesMissingTest,
TestConfigSetsAPI, TestRandomRequestDistribution, FileBasedSpellCheckerTest,
TestManagedResourceStorage, MigrateRouteKeyTest, TestPerFieldSimilarityClassic,
EchoParamsTest, TestCSVLoader, TestFaceting, TestMiniSolrCloudCluster,
QueryEqualityTest, TestDistribIDF, CursorMarkTest, SolrTestCaseJ4Test,
TestManagedStopFilterFactory, DistributedQueryElevationComponentTest,
TestBulkSchemaAPI, TestFunctionQuery, TestDistributedStatsComponentCardinality,
TestDocBasedVersionConstraints, TestConfig, StandardRequestHandlerTest,
AnalysisAfterCoreReloadTest, ZkStateReaderTest, GraphQueryTest, TestLazyCores,
AssignTest, TestLMDirichletSimilarityFactory, HdfsDirectoryTest,
HdfsSyncSliceTest, DistributedFacetPivotSmallAdvancedTest,
HdfsBasicDistributedZkTest, PingRequestHandlerTest, TestBinaryField,
TestRangeQuery, PeerSyncTest, TestIBSimilarityFactory, TestRemoteStreaming,
CdcrVersionReplicationTest, TestConfigReload, OutputWriterTest,
TestConfigSetProperties, TestOverriddenPrefixQueryForCustomFieldType,
MultiThreadedOCPTest, TestSolrCLIRunExample, TestArbitraryIndexDir,
TestRandomFaceting, DistribCursorPagingTest, PolyFieldTest,
SolrRequestParserTest, QueryResultKeyTest, RequiredFieldsTest, DeleteShardTest,
JsonLoaderTest, DisMaxRequestHandlerTest, TestReRankQParserPlugin,
TestScoreJoinQPNoScore, ConcurrentDeleteAndCreateCollectionTest,
TestRandomCollapseQParserPlugin, SuggestComponentContextFilterQueryTest,
BasicAuthIntegrationTest, TestMinMaxOnMultiValuedField, TestValueSourceCache,
TestExactSharedStatsCache, URLClassifyProcessorTest, TestAnalyzedSuggestions,
TestFastOutputStream, AddBlockUpdateTest,
OverriddenZkACLAndCredentialsProvidersTest, TestSerializedLuceneMatchVersion,
PKIAuthenticationIntegrationTest, RestartWhileUpdatingTest,
DistributedSpellCheckComponentTest, TestLeaderInitiatedRecoveryThread,
TestDocSet, TestBulkSchemaConcurrent, OverseerCollectionConfigSetProcessorTest,
BasicDistributedZkTest, ChaosMonkeySafeLeaderTest,
CollectionsAPIDistributedZkTest, BasicZkTest, TestReplicationHandler,
CollectionReloadTest, CollectionsAPIAsyncDistributedZkTest,
ConnectionManagerTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest,
DistributedQueueTest, DistributedVersionInfoTest,
LeaderInitiatedRecoveryOnShardRestartTest]
[junit4] Completed [452/559] on J0 in 419.70s, 1 test, 1 failure <<<
FAILURES!
[...truncated 337 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987:
There were test failures: 559 suites (8 ignored), 2216 tests, 1 error, 1
failure, 32 ignored (18 assumptions) [seed: 344D7521C48CA104]
Total time: 207 minutes 21 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
