Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/851/
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
No registered leader was found after waiting for 30000ms , collection:
halfdeletedcollection2 slice: shard1
Stack Trace:
org.apache.solr.common.SolrException: No registered leader was found after
waiting for 30000ms , collection: halfdeletedcollection2 slice: shard1
at
org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:637)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deleteCollectionWithDownNodes(CollectionsAPIDistributedZkTest.java:259)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:163)
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:1660)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916)
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:875)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822)
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)
Build Log:
[...truncated 10440 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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/init-core-data-001
[junit4] 2> 1709405 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[8D1F5EBE10978A1F]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1709406 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[8D1F5EBE10978A1F]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1709408 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1709408 INFO (Thread-4024) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1709408 INFO (Thread-4024) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1709508 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.ZkTestServer start zk server on port:36367
[junit4] 2> 1709509 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1709509 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1709512 INFO (zkCallback-1534-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7ba03227
name:ZooKeeperConnection Watcher:127.0.0.1:36367 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1709512 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1709512 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1709512 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1709515 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1709515 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1709516 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@31f6e84c
name:ZooKeeperConnection Watcher:127.0.0.1:36367/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1709516 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1709516 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1709516 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 1709518 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 1709520 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 1709521 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 1709522 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709522 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1709525 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709525 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 1709526 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709527 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1709528 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709528 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1709529 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709530 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 1709531 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709531 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 1709532 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709533 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1709534 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709534 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1709535 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709536 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1709537 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709537 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1709538 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709539 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1709835 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1
[junit4] 2> 1709836 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1709838 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@51d54158{/,null,AVAILABLE}
[junit4] 2> 1709840 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@71120089{HTTP/1.1}{127.0.0.1:53653}
[junit4] 2> 1709840 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.e.j.s.Server Started @1712746ms
[junit4] 2> 1709841 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/tempDir-001/control/data,
hostContext=/, hostPort=53653,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores}
[junit4] 2> 1709841 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 1709841 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/'
[junit4] 2> 1709841 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1709841 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1709856 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1709856 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1709857 INFO (zkCallback-1536-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@27a3616c
name:ZooKeeperConnection Watcher:127.0.0.1:36367/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1709857 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1709858 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1709858 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1709859 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/solr.xml
[junit4] 2> 1709863 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.CoresLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores
[junit4] 2> 1709863 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.CoreContainer New CoreContainer 966657209
[junit4] 2> 1709863 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/]
[junit4] 2> 1709863 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.CoreContainer loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/lib
[junit4] 2> 1709863 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to
classloader: lib (resolved as:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/lib).
[junit4] 2> 1709870 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1709871 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1709872 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1709872 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1709872 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36367/solr
[junit4] 2> 1709872 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1709872 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1709873 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1709874 INFO (zkCallback-1538-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@266405ab
name:ZooKeeperConnection Watcher:127.0.0.1:36367 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1709874 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1709874 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1709875 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1709876 INFO
(zkCallback-1539-thread-1-processing-n:127.0.0.1:53653_) [n:127.0.0.1:53653_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@8e94b12 name:ZooKeeperConnection
Watcher:127.0.0.1:36367/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1709876 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 1709877 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1709879 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 1709881 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 1709883 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 1709884 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 1709886 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1709887 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1709888 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1709889 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1709890 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53653_
[junit4] 2> 1709890 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:53653_
[junit4] 2> 1709891 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1709892 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 1709893 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1709894 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/94859149466402820-127.0.0.1:53653_-n_0000000000
[junit4] 2> 1709894 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:53653_
[junit4] 2> 1709894 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1709895 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.Overseer Overseer
(id=94859149466402820-127.0.0.1:53653_-n_0000000000) starting
[junit4] 2> 1709896 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1709901 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1709901 INFO
(OverseerCollectionConfigSetProcessor-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] o.a.s.c.OverseerTaskProcessor Process current queue
of overseer operations
[junit4] 2> 1709901 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 1709902 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1709925 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 1709925 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1709926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.CoresLocator Looking for core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores
[junit4] 2> 1709926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] 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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1,
collection=control_collection,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1709926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.CoresLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/
[junit4] 2> 1709927 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1709928 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2
[junit4] 2> 1709928 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:53653_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 1709928 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 1709928 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 1709929 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] 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:53653";,
[junit4] 2> "node_name":"127.0.0.1:53653_",
[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> 1709929 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 1709930 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] 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:53653";,
[junit4] 2> "node_name":"127.0.0.1:53653_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1709930 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
control_collection
[junit4] 2> 1709930 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 1709931 INFO
(zkCallback-1539-thread-1-processing-n:127.0.0.1:53653_) [n:127.0.0.1:53653_
] 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> 1710930 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 1710930 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.ZkController
Check for collection zkNode:control_collection
[junit4] 2> 1710931 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 1710931 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config
from:/collections/control_collection
[junit4] 2> 1710932 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1
specified config exists in ZooKeeper
[junit4] 2> 1710932 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/'
[junit4] 2> 1710932 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1710933 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1710949 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 1710953 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 1710956 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1710964 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.Config Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 1710965 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.s.IndexSchema
Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1710969 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1711060 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1711066 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.s.IndexSchema
default search field in schema is text
[junit4] 2> 1711067 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.s.IndexSchema
unique key field: id
[junit4] 2> 1711074 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1711076 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1711077 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 1711078 WARN
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1711078 WARN
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1711078 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 1711079 WARN
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1711079 WARN
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1711080 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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/J2
[junit4] 2> 1711080 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 1711080 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1711080 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/],
dataDir=[null]
[junit4] 2> 1711080 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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@223ae7dd
[junit4] 2> 1711081 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/data
[junit4] 2> 1711081 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/data/index/
[junit4] 2> 1711081 WARN
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1711081 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/data/index
[junit4] 2> 1711081 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7895336299700465]
[junit4] 2> 1711082 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1feaad5f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@37b46fe2),segFN=segments_1,generation=1}
[junit4] 2> 1711082 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1711082 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53653_
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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/data/
[junit4] 2> 1711082 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53653_
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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 1711085 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 1711085 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 1711085 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1711085 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 1711085 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1711085 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711086 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711086 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711086 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711086 WARN
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711087 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1711088 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1711089 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1711090 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1711093 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711093 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711093 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711093 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711094 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1711094 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1711094 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.5556640625,
noCFSRatio=0.32724945874590033]
[junit4] 2> 1711095 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1feaad5f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@37b46fe2),segFN=segments_1,generation=1}
[junit4] 2> 1711095 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1711095 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@781a15e4[collection1] main
[junit4] 2> 1711095 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711096 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711096 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711096 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1711096 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1711096 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711097 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711097 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711097 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1711097 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1711097 INFO
(searcherExecutor-4229-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@781a15e4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1711098 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711098 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711098 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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> 1711098 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ 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 1517746393232441344
[junit4] 2> 1711100 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version
1517746393232441344
[junit4] 2> 1711100 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 1711100 INFO
(coreLoadExecutor-4228-thread-1-processing-n:127.0.0.1:53653_)
[n:127.0.0.1:53653_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1711100 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
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:53653
collection:control_collection shard:shard1
[junit4] 2> 1711101 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
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> 1711104 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
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/94859149466402820-core_node1-n_0000000000
[junit4] 2> 1711104 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
[junit4] 2> 1711105 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1711106 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
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> 1711106 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:53653/collection1/
[junit4] 2> 1711106 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1711106 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:53653/collection1/ has no replicas
[junit4] 2> 1711106 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] 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> 1711106 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1711108 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 1711110 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
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:53653/collection1/ shard1
[junit4] 2> 1711110 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] 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:53653";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1711212 INFO
(zkCallback-1539-thread-1-processing-n:127.0.0.1:53653_) [n:127.0.0.1:53653_
] 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> 1711261 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are http://127.0.0.1:53653/collection1/ and leader is
http://127.0.0.1:53653/collection1/
[junit4] 2> 1711261 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
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:53653
[junit4] 2> 1711261 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1711261 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 1711261 INFO
(coreZkRegister-4222-thread-1-processing-n:127.0.0.1:53653_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53653_
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> 1711263 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] 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:53653";,
[junit4] 2> "node_name":"127.0.0.1:53653_",
[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> 1711263 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] 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:53653";,
[junit4] 2> "node_name":"127.0.0.1:53653_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1711365 INFO
(zkCallback-1539-thread-1-processing-n:127.0.0.1:53653_) [n:127.0.0.1:53653_
] 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> 1711429 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1711430 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1711431 INFO (zkCallback-1541-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5fd11fbd
name:ZooKeeperConnection Watcher:127.0.0.1:36367/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1711431 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1711431 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1711431 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1711433 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1711741 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores/collection1
[junit4] 2> 1711742 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001
[junit4] 2> 1711743 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1711744 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7d34cdc3{/,null,AVAILABLE}
[junit4] 2> 1711745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@314197a0{HTTP/1.1}{127.0.0.1:44725}
[junit4] 2> 1711745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.e.j.s.Server Started @1714650ms
[junit4] 2> 1711745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=44725,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores}
[junit4] 2> 1711745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 1711745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/'
[junit4] 2> 1711745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1711745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1711759 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1711760 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1711761 INFO (zkCallback-1542-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6297edbb
name:ZooKeeperConnection Watcher:127.0.0.1:36367/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1711761 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1711761 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1711762 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1711762 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/solr.xml
[junit4] 2> 1711766 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.CoresLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores
[junit4] 2> 1711767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.CoreContainer New CoreContainer 440940465
[junit4] 2> 1711767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/]
[junit4] 2> 1711767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.CoreContainer loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/lib
[junit4] 2> 1711767 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to
classloader: lib (resolved as:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/lib).
[junit4] 2> 1711774 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
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> 1711776 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1711776 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1711776 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1711776 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36367/solr
[junit4] 2> 1711777 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1711777 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1711780 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1711780 INFO (zkCallback-1544-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3136dfc6
name:ZooKeeperConnection Watcher:127.0.0.1:36367 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1711781 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1711781 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1711783 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1711783 INFO
(zkCallback-1545-thread-1-processing-n:127.0.0.1:44725_) [n:127.0.0.1:44725_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5e02d7a7
name:ZooKeeperConnection Watcher:127.0.0.1:36367/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1711783 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 1711788 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 1712792 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44725_
[junit4] 2> 1712793 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:44725_
[junit4] 2> 1712794 INFO (zkCallback-1541-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> 1712794 INFO
(zkCallback-1539-thread-1-processing-n:127.0.0.1:53653_) [n:127.0.0.1:53653_
] 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> 1712794 INFO
(zkCallback-1545-thread-1-processing-n:127.0.0.1:44725_) [n:127.0.0.1:44725_
] 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> 1712795 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1712796 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/94859149466402824-127.0.0.1:44725_-n_0000000001
[junit4] 2> 1712796 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/94859149466402820-127.0.0.1:53653_-n_0000000000 to
know if I could be the leader
[junit4] 2> 1712807 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 1712807 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1712808 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.CoresLocator Looking for core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores
[junit4] 2> 1712809 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] 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/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores/collection1,
collection=collection1,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores/collection1/,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1712809 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.CoresLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores/collection1/
[junit4] 2> 1712809 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1712810 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 1712810 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2
[junit4] 2> 1712810 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 1712810 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8D1F5EBE10978A1F])
[n:127.0.0.1:44725_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 1712812 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] 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:44725";,
[junit4] 2> "node_name":"127.0.0.1:44725_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 1712812 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.ZkController look
for our core node name
[junit4] 2> 1712812 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:44725";,
[junit4] 2> "node_name":"127.0.0.1:44725_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1712812 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
collection1
[junit4] 2> 1712812 INFO
(OverseerStateUpdate-94859149466402820-127.0.0.1:53653_-n_0000000000)
[n:127.0.0.1:53653_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard2
[junit4] 2> 1712918 INFO (zkCallback-1541-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 2)
[junit4] 2> 1712918 INFO
(zkCallback-1545-thread-1-processing-n:127.0.0.1:44725_) [n:127.0.0.1:44725_
] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 2)
[junit4] 2> 1712919 INFO
(zkCallback-1539-thread-1-processing-n:127.0.0.1:53653_) [n:127.0.0.1:53653_
] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred
- updating... (live nodes size: 2)
[junit4] 2> 1713813 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.ZkController waiting
to find shard id in clusterstate for collection1
[junit4] 2> 1713813 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.ZkController Check
for collection zkNode:collection1
[junit4] 2> 1713813 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 1713813 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load
collection config from:/collections/collection1
[junit4] 2> 1713814 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1713814 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 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/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores/collection1/'
[junit4] 2> 1713814 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader
JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1713814 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader
solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1713826 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.Config loaded config
solrconfig.xml with version 0
[junit4] 2> 1713831 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.SolrConfig current
version of requestparams : -1
[junit4] 2> 1713834 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.0.0
[junit4] 2> 1713854 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.Config Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 1713855 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading
Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1713859 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1713947 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1713955 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.s.IndexSchema default
search field in schema is text
[junit4] 2> 1713957 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique
key field: id
[junit4] 2> 1713966 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1713969 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1713971 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 1713972 WARN
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1713972 WARN
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1713972 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 1713973 WARN
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1713973 WARN
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1713974 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 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/J2
[junit4] 2> 1713974 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1713974 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1713974 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 s:shard2 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/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001/shard-1-001/cores/collection1/],
dataDir=[null]
[junit4] 2> 1713974 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 s:shard2 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@223ae7dd
[junit4] 2> 1713975 INFO
(coreLoadExecutor-4239-thread-1-processing-n:127.0.0.1:44725_)
[n:127.0.0.1:44725_ c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/
[...truncated too long message...]
lrClient.java:372)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1573)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1594)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:876)Throwable
#6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an
uncaught exception in thread: Thread[id=11296, name=collection5,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:35715: collection already exists:
awholynewstresscollection_collection5_0
[junit4] > at
__randomizedtesting.SeedInfo.seed([8D1F5EBE10978A1F]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1573)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1594)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:876)Throwable
#7: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an
uncaught exception in thread: Thread[id=11294, name=collection3,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:35715: collection already exists:
awholynewstresscollection_collection3_0
[junit4] > at
__randomizedtesting.SeedInfo.seed([8D1F5EBE10978A1F]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1573)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1594)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:876)
[junit4] 2> 1969859 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[8D1F5EBE10978A1F]-worker)
[n:127.0.0.1:39210_ c:awholynewstresscollection_collection3_0 s:shard5
r:core_node19 x:awholynewstresscollection_collection3_0_shard5_replica3]
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/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_8D1F5EBE10978A1F-001
[junit4] 2> Nov 13, 2015 5:33:32 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60):
{other_tl1=PostingsFormat(name=Direct),
range_facet_l_dv=PostingsFormat(name=Memory doPackFST= true),
_version_=PostingsFormat(name=Direct), multiDefault=Lucene50(blocksize=128),
a_t=Lucene50(blocksize=128), intDefault=PostingsFormat(name=Direct),
a_i1=Lucene50(blocksize=128), id=PostingsFormat(name=Memory doPackFST= true),
range_facet_i_dv=Lucene50(blocksize=128),
text=PostingsFormat(name=LuceneFixedGap),
range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)},
docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting),
range_facet_i_dv=DocValuesFormat(name=Lucene54),
timestamp=DocValuesFormat(name=Lucene54)},
sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=sl_SI,
timezone=Africa/Lubumbashi
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_66 (64-bit)/cpus=4,threads=1,free=145954864,total=502267904
[junit4] 2> NOTE: All tests run in this JVM:
[TestReversedWildcardFilterFactory, TestManagedSynonymFilterFactory,
TestConfigSetsAPI, AutoCommitTest, FullSolrCloudDistribCmdsTest,
SpatialRPTFieldTypeTest, TestPostingsSolrHighlighter, TestSearchPerf,
TestNRTOpen, DocExpirationUpdateProcessorFactoryTest,
FieldAnalysisRequestHandlerTest, BitVectorTest, TestRestoreCore,
HdfsLockFactoryTest, CollectionReloadTest, TestCryptoKeys,
BinaryUpdateRequestHandlerTest, ScriptEngineTest, TestStressRecovery,
TestCrossCoreJoin, TestSolrQueryParserResource,
TestPHPSerializedResponseWriter, SolrCoreCheckLockOnStartupTest,
FacetPivotSmallTest, TestManagedSchema, DistributedFacetPivotWhiteBoxTest,
DistanceUnitsTest, TestMiniSolrCloudCluster, SharedFSAutoReplicaFailoverTest,
HighlighterConfigTest, TestReloadAndDeleteDocs, DirectSolrConnectionTest,
DistribDocExpirationUpdateProcessorTest, OverseerTest, ShardSplitTest,
TestJmxIntegration, CursorPagingTest, SearchHandlerTest, TestSchemaManager,
SliceStateTest, TestIndexingPerformance, PreAnalyzedUpdateProcessorTest,
TestObjectReleaseTracker, TestConfigSetsAPIExclusivity, RecoveryZkTest,
TestSolrDeletionPolicy1, BasicDistributedZk2Test, BadIndexSchemaTest,
CachingDirectoryFactoryTest, TestSchemaSimilarityResource, SampleTest,
CoreAdminRequestStatusTest, TestConfigSets, TestSQLHandler,
TestShortCircuitedRequests, TestScoreJoinQPScore, HdfsNNFailoverTest,
TestHighFrequencyDictionaryFactory, TestRTimerTree, CircularListTest,
TestRuleBasedAuthorizationPlugin, TestRecovery, ClusterStateTest,
TestDefaultStatsCache, TestPerFieldSimilarity, TestQueryWrapperFilter,
TestGroupingSearch, TestDistribDocBasedVersion,
TestExclusionRuleCollectionAccess, TestStressReorder,
IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestSolrXml,
TestNoOpRegenerator, CustomCollectionTest, TestFastWriter, TestConfigOverlay,
TestSolr4Spatial2, TestXIncludeConfig, LukeRequestHandlerTest, PluginInfoTest,
SaslZkACLProviderTest, TestSort, TestCloudSchemaless, TestCharFilters,
TestFilteredDocIdSet, TestReplicationHandlerBackup, DistributedQueueTest,
TestCopyFieldCollectionResource, ChaosMonkeySafeLeaderTest,
UnloadDistributedZkTest, CollectionsAPIDistributedZkTest]
[junit4] Completed [266/558] on J2 in 260.74s, 1 test, 1 error <<< FAILURES!
[...truncated 940 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: 558 suites (8 ignored), 2214 tests, 1 error, 32
ignored (18 assumptions) [seed: 8D1F5EBE10978A1F]
Total time: 194 minutes 23 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: [email protected]
For additional commands, e-mail: [email protected]
