Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/790/
3 tests failed.
REGRESSION: org.apache.solr.cloud.DistribJoinFromCollectionTest.test
Error Message:
Expected: not "1.0" got: "1.0"
Stack Trace:
java.lang.AssertionError:
Expected: not "1.0"
got: "1.0"
at
__randomizedtesting.SeedInfo.seed([AAFB6F0BC146A2FD:22AF50D16FBACF05]:0)
at org.junit.Assert.assertThat(Assert.java:780)
at org.junit.Assert.assertThat(Assert.java:738)
at
org.apache.solr.cloud.DistribJoinFromCollectionTest.assertScore(DistribJoinFromCollectionTest.java:170)
at
org.apache.solr.cloud.DistribJoinFromCollectionTest.testJoins(DistribJoinFromCollectionTest.java:132)
at
org.apache.solr.cloud.DistribJoinFromCollectionTest.test(DistribJoinFromCollectionTest.java:100)
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:1627)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
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:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
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:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
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:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=1649, name=collection5,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=1649, name=collection5, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:36605/_io/tv: collection already exists:
awholynewstresscollection_collection5_3
at __randomizedtesting.SeedInfo.seed([AAFB6F0BC146A2FD]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888)
FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=20492, name=collection5,
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=20492, name=collection5, state=RUNNABLE,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:45331/_dnu/ah: Could not find collection :
awholynewstresscollection_collection5_2
at __randomizedtesting.SeedInfo.seed([AAFB6F0BC146A2FD]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895)
Build Log:
[...truncated 9986 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/init-core-data-001
[junit4] 2> 125508 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[AAFB6F0BC146A2FD]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 125508 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[AAFB6F0BC146A2FD]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_io/tv
[junit4] 2> 125518 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 125531 INFO (Thread-584) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 125531 INFO (Thread-584) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 125631 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.ZkTestServer start zk server on port:38691
[junit4] 2> 125631 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 125648 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 125659 INFO (zkCallback-182-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@62d8f189
name:ZooKeeperConnection Watcher:127.0.0.1:38691 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 125659 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 125660 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 125660 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 125669 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 125696 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 125708 INFO (zkCallback-183-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3a28f54a
name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 125708 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 125709 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 125709 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 125711 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 125712 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 125713 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 125714 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125714 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 125716 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125717 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 125718 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125718 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 125722 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125722 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 125724 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125724 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 125725 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125725 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 125726 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125727 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 125728 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125728 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 125729 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125730 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 125731 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125731 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 125732 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 125733 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 126183 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1
[junit4] 2> 126185 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 126203 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4d88cf05{/_io/tv,null,AVAILABLE}
[junit4] 2> 126205 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@2ff0102f{HTTP/1.1}{127.0.0.1:33752}
[junit4] 2> 126206 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.e.j.s.Server Started @129386ms
[junit4] 2> 126206 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/tempDir-001/control/data,
hostContext=/_io/tv, hostPort=33752,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores}
[junit4] 2> 126206 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 126206 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/'
[junit4] 2> 126224 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 126233 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 126234 INFO (zkCallback-184-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@64a549f8
name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 126234 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 126235 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 126236 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 126236 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/solr.xml
[junit4] 2> 126241 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.CoresLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores
[junit4] 2> 126242 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1399687175
[junit4] 2> 126242 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/]
[junit4] 2> 126242 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.CoreContainer loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/lib
[junit4] 2> 126242 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/lib).
[junit4] 2> 126260 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 126262 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 126262 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 126262 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 126262 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38691/solr
[junit4] 2> 126263 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 126263 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 126264 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 126278 INFO (zkCallback-186-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@34acd67a
name:ZooKeeperConnection Watcher:127.0.0.1:38691 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 126279 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 126279 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 126306 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ConnectionManager Waiting for client
to connect to ZooKeeper
[junit4] 2> 126307 INFO
(zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@692be441
name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 126307 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ConnectionManager Client is
connected to ZooKeeper
[junit4] 2> 126309 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer/queue
[junit4] 2> 126311 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 126315 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 126318 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 126320 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 126323 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 126324 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 126326 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/clusterstate.json
[junit4] 2> 126327 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 126328 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33752__io%2Ftv
[junit4] 2> 126329 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:33752__io%2Ftv
[junit4] 2> 126330 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect
[junit4] 2> 126332 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 126333 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 126333 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.LeaderElector Joined leadership
election with path:
/overseer_elect/election/94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000
[junit4] 2> 126334 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.OverseerElectionContext I am going to
be the leader 127.0.0.1:33752__io%2Ftv
[junit4] 2> 126334 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/leader
[junit4] 2> 126337 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer Overseer
(id=94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) starting
[junit4] 2> 126339 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/overseer/queue-work
[junit4] 2> 126347 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.OverseerAutoReplicaFailoverThread
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 126348 INFO
(OverseerCollectionConfigSetProcessor-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.OverseerTaskProcessor Process current
queue of overseer operations
[junit4] 2> 126349 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer Starting to work on the main
queue
[junit4] 2> 126349 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 126355 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 126383 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoreContainer Security conf doesn't
exist. Skipping setup for authorization module.
[junit4] 2> 126384 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoreContainer No authentication plugin
used.
[junit4] 2> 126384 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoresLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores
[junit4] 2> 126385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1,
collection=control_collection,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 126385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoresLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/
[junit4] 2> 126385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 126391 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
[junit4] 2> 126391 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.s.SolrDispatchFilter
SolrDispatchFilter.init() done
[junit4] 2> 126392 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.ZkController publishing state=down
[junit4] 2> 126392 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.ZkController numShards not found on descriptor - reading it from system
property
[junit4] 2> 126394 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.ZkController look for our core node name
[junit4] 2> 126395 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] 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:33752/_io/tv";,
[junit4] 2> "node_name":"127.0.0.1:33752__io%2Ftv",
[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> 126397 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] 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:33752/_io/tv";,
[junit4] 2> "node_name":"127.0.0.1:33752__io%2Ftv",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 126397 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ClusterStateMutator building a new
cName: control_collection
[junit4] 2> 126399 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 126401 INFO
(zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] 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> 126401 INFO
(zkCallback-187-thread-2-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] 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> 127395 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 127395 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 127395 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 127395 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config
from:/collections/control_collection
[junit4] 2> 127396 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1
specified config exists in ZooKeeper
[junit4] 2> 127396 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/'
[junit4] 2> 127420 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 127431 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 127435 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 127442 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 127443 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 127465 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 127556 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 127565 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 127566 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 127595 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 127597 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 127599 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 127600 WARN
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 127601 WARN
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 127601 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 127602 WARN
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 127602 WARN
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 127604 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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/J1
[junit4] 2> 127604 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 127604 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 127604 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/],
dataDir=[null]
[junit4] 2> 127604 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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@6b6fa57a
[junit4] 2> 127605 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data
[junit4] 2> 127605 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/index/
[junit4] 2> 127605 WARN
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 127606 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/index
[junit4] 2> 127606 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=9, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=92.2255859375,
floorSegmentMB=2.0126953125, forceMergeDeletesPctAllowed=13.142628610524959,
segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 127611 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=RawDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/index-SimpleFSDirectory-001
lockFactory=org.apache.lucene.store.NativeFSLockFactory@289889a9),segFN=segments_1,generation=1}
[junit4] 2> 127612 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 127613 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:33752__io%2Ftv
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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/
[junit4] 2> 127614 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:33752__io%2Ftv
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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 127622 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 127622 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 127623 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 127623 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 127623 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 127623 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127624 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127624 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127624 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127642 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 127643 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 127644 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 127645 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 127649 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/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> 127650 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127650 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127651 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127651 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 127652 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 127652 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=23, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=70.0029296875,
floorSegmentMB=0.3349609375, forceMergeDeletesPctAllowed=10.432124189999241,
segmentsPerTier=8.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 127654 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=RawDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/index-SimpleFSDirectory-001
lockFactory=org.apache.lucene.store.NativeFSLockFactory@289889a9),segFN=segments_1,generation=1}
[junit4] 2> 127654 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 127654 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@52ebb3fc[collection1]
main
[junit4] 2> 127654 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127655 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127655 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127656 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 127656 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 127656 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127658 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127658 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127658 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 127658 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 127659 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127659 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127659 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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> 127659 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv 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 1511846897722064896
[junit4] 2> 127661 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with
highest version 1511846897722064896
[junit4] 2> 127661 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 127661 INFO
(searcherExecutor-423-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@52ebb3fc[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 127665 INFO
(coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 127666 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv 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:33752/_io/tv collection:control_collection shard:shard1
[junit4] 2> 127667 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv 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> 127681 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv 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/94490430969217028-core_node1-n_0000000000
[junit4] 2> 127682 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process
for shard shard1
[junit4] 2> 127685 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 127685 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv 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> 127686 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:33752/_io/tv/collection1/
[junit4] 2> 127686 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 127686 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:33752/_io/tv/collection1/
has no replicas
[junit4] 2> 127686 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv 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:33752/_io/tv/collection1/ shard1
[junit4] 2> 127689 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] 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> 127693 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.c.SolrZkClient makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 127706 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader
registration node
[junit4] 2> 127718 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] 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:33752/_io/tv";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 127820 INFO
(zkCallback-187-thread-2-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] 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> 127821 INFO
(zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] 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> 127859 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController We are
http://127.0.0.1:33752/_io/tv/collection1/ and leader is
http://127.0.0.1:33752/_io/tv/collection1/
[junit4] 2> 127859 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv 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:33752/_io/tv
[junit4] 2> 127859 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 127860 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 127860 INFO
(coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:33752__io%2Ftv 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> 127861 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] 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:33752/_io/tv";,
[junit4] 2> "node_name":"127.0.0.1:33752__io%2Ftv",
[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> 127862 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] 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:33752/_io/tv";,
[junit4] 2> "node_name":"127.0.0.1:33752__io%2Ftv",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 127894 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 127912 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 127925 INFO (zkCallback-189-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4a3a7150
name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 127926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 127926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 127926 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 127931 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 127931 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 127931 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 127941 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 127943 INFO (zkCallback-190-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@1626e664
name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 127943 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 127943 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 127945 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 2
[junit4] 2> 127946 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ClusterStateMutator building a new
cName: collection1
[junit4] 2> 127946 INFO (zkCallback-189-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: 1)
[junit4] 2> 127946 INFO
(zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] 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> 127947 INFO
(zkCallback-187-thread-2-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] 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> 128053 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ZkStateWriter going to
create_collection /collections/collection1/state.json
[junit4] 2> 128801 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores/collection1
[junit4] 2> 128803 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001
[junit4] 2> 128807 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 128829 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1cc52219{/_io/tv,null,AVAILABLE}
[junit4] 2> 128830 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@54575593{HTTP/1.1}{127.0.0.1:56119}
[junit4] 2> 128830 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.e.j.s.Server Started @132011ms
[junit4] 2> 128830 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/_io/tv, hostPort=56119,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores}
[junit4] 2> 128831 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 128832 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/'
[junit4] 2> 128879 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 128893 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 128902 INFO (zkCallback-191-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5325ebc1
name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 128902 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 128902 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 128903 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 128904 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/solr.xml
[junit4] 2> 128909 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.CoresLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores
[junit4] 2> 128909 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.CoreContainer New CoreContainer 15035131
[junit4] 2> 128909 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/]
[junit4] 2> 128909 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.CoreContainer loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/lib
[junit4] 2> 128909 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/lib).
[junit4] 2> 128928 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
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> 128930 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 128930 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 128930 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 128930 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38691/solr
[junit4] 2> 128930 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 128931 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 128931 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 128934 INFO (zkCallback-193-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@41f8ea37
name:ZooKeeperConnection Watcher:127.0.0.1:38691 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 128935 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 128935 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 128939 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.ConnectionManager Waiting for client
to connect to ZooKeeper
[junit4] 2> 128943 INFO
(zkCallback-194-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3adacb99
name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 128943 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.ConnectionManager Client is
connected to ZooKeeper
[junit4] 2> 128948 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 129953 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56119__io%2Ftv
[junit4] 2> 129953 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:56119__io%2Ftv
[junit4] 2> 129954 INFO (zkCallback-189-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> 129955 INFO
(zkCallback-194-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv ] 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> 129955 INFO
(zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] 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> 129955 INFO
(zkCallback-187-thread-2-processing-n:127.0.0.1:33752__io%2Ftv)
[n:127.0.0.1:33752__io%2Ftv ] 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> 129958 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 129959 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.LeaderElector Joined leadership
election with path:
/overseer_elect/election/94490430969217033-127.0.0.1:56119__io%2Ftv-n_0000000001
[junit4] 2> 129960 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000
to know if I could be the leader
[junit4] 2> 129979 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoreContainer Security conf doesn't
exist. Skipping setup for authorization module.
[junit4] 2> 129979 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoreContainer No authentication plugin
used.
[junit4] 2> 129980 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoresLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores
[junit4] 2> 129981 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores/collection1,
collection=collection1,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores/collection1/,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 129981 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoresLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores/collection1/
[junit4] 2> 129981 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 129984 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 129991 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
[junit4] 2> 129991 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD])
[n:127.0.0.1:56119__io%2Ftv ] o.a.s.s.SolrDispatchFilter
SolrDispatchFilter.init() done
[junit4] 2> 129991 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 129994 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] 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:56119/_io/tv";,
[junit4] 2> "node_name":"127.0.0.1:56119__io%2Ftv",
[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> 129994 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] 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:56119/_io/tv";,
[junit4] 2> "node_name":"127.0.0.1:56119__io%2Ftv",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 129994 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Collection already
exists with numShards=2
[junit4] 2> 129995 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 129997 INFO
(OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000)
[n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ZkStateWriter going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 130001 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController
Registering watch for collection collection1
[junit4] 2> 130001 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1]
o.a.s.c.c.ZkStateReader addZkWatch collection1
[junit4] 2> 130002 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1]
o.a.s.c.c.ZkStateReader Add data for collection1 ver 1
[junit4] 2> 130002 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 130002 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 130002 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController
Check for collection zkNode:collection1
[junit4] 2> 130003 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 130003 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv)
[n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1
[junit4] 2> 130004 INFO
(coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0
[...truncated too long message...]
nit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.NullPointerException
[junit4] 2> at
org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
[junit4] 2> at
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
[junit4] 2> at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
[junit4] 2> ... 53 more
[junit4] 2> 4896403 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[AAFB6F0BC146A2FD]-worker)
[n:127.0.0.1:47214__dnu%2Fah c:addReplicaColl s:shard2 r:core_node1
x:addReplicaColl_shard2_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> Sep 09, 2015 11:58:46 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=DefaultSimilarity, locale=sv, timezone=Asia/Dubai
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_45 (64-bit)/cpus=4,threads=3,free=46723448,total=533200896
[junit4] 2> NOTE: All tests run in this JVM: [MBeansHandlerTest,
HLLSerializationTest, ClusterStateUpdateTest, BitVectorTest,
TestFreeTextSuggestions, MinimalSchemaTest, SpellCheckCollatorTest,
DistributedTermsComponentTest, TestMissingGroups, TestAuthenticationFramework,
NotRequiredUniqueKeyTest, AnalyticsQueryTest, CollectionsAPIDistributedZkTest,
TestOverriddenPrefixQueryForCustomFieldType, TestRuleBasedAuthorizationPlugin,
TestStressVersions, LoggingHandlerTest, TestFieldTypeResource,
TermVectorComponentTest, TestJsonRequest, MergeStrategyTest,
BasicFunctionalityTest, CSVRequestHandlerTest, DistributedExpandComponentTest,
TestSolrQueryParserResource, ZkCLITest, TestBlendedInfixSuggestions,
TestObjectReleaseTracker, SuggestComponentTest, TestCloudPivotFacet,
ShardRoutingTest, TestReloadDeadlock, CachingDirectoryFactoryTest,
FastVectorHighlighterTest, DistributedFacetPivotLongTailTest,
ChaosMonkeySafeLeaderTest, DocumentBuilderTest, SimplePostToolTest,
HdfsRecoverLeaseTest, ShardRoutingCustomTest,
AsyncCallRequestStatusResponseTest, ChaosMonkeyNothingIsSafeTest,
TestQueryTypes, TestStressUserVersions, TriLevelCompositeIdRoutingTest,
TestDynamicLoading, CollectionsAPIAsyncDistributedZkTest,
TestLeaderElectionZkExpiry, AddSchemaFieldsUpdateProcessorFactoryTest,
SpellPossibilityIteratorTest, TestUtils, TestLFUCache, DOMUtilTest,
TermsComponentTest, SolrCmdDistributorTest, TestShortCircuitedRequests,
BJQParserTest, CoreAdminRequestStatusTest,
DocExpirationUpdateProcessorFactoryTest, ZkSolrClientTest, ConvertedLegacyTest,
TestConfigSets, TestJettySolrRunner, SoftAutoCommitTest,
TestDFRSimilarityFactory, TestUpdate, TestQuerySenderNoQuery, MultiTermTest,
TestManagedSchema, ShardSplitTest, TestQuerySenderListener, TestSchemaManager,
SliceStateTest, HLLUtilTest, TestRTGBase, FacetPivotSmallTest,
UniqFieldsUpdateProcessorFactoryTest, TestCustomDocTransformer,
DistanceFunctionTest, PreAnalyzedUpdateProcessorTest,
DeleteLastCustomShardedReplicaTest, TestClusterStateMutator,
HdfsLockFactoryTest, SpatialFilterTest, TestWordDelimiterFilterFactory,
TestBM25SimilarityFactory, DistributedFacetPivotSmallTest,
TestFileDictionaryLookup, TestCodecSupport, IndexBasedSpellCheckerTest,
TestRandomFaceting, TestZkChroot, AutoCommitTest, TestDistributedGrouping,
TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest,
ZkControllerTest, TestRealTimeGet, TestRangeQuery, TestCoreContainer,
TestSolr4Spatial, StatsComponentTest, TestLazyCores, TestBadConfig,
CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest,
CoreAdminHandlerTest, TestStressLucene, TestCSVLoader,
SchemaVersionSpecificBehaviorTest, QueryEqualityTest, SortByFunctionTest,
TestRemoteStreaming, DocValuesMultiTest, TestSolrDeletionPolicy1,
XsltUpdateRequestHandlerTest, DebugComponentTest, LukeRequestHandlerTest,
DirectSolrSpellCheckerTest, RequestHandlersTest, MoreLikeThisHandlerTest,
FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest,
TestCollationField, JSONWriterTest, BinaryUpdateRequestHandlerTest,
TestPHPSerializedResponseWriter, SearchHandlerTest, ResponseLogComponentTest,
SolrIndexConfigTest, TestMergePolicyConfig, TestSolrDeletionPolicy2,
NumericFieldsTest, TestConfig, TestFuzzyAnalyzedSuggestions,
ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter,
DirectSolrConnectionTest, TestCharFilters, TestXIncludeConfig,
TestSweetSpotSimilarityFactory, TestPerFieldSimilarity,
TestLMJelinekMercerSimilarityFactory, TestJmxMonitoredMap, ResourceLoaderTest,
TestFastOutputStream, URLClassifyProcessorTest, TestFastLRUCache,
DateMathParserTest, PreAnalyzedFieldTest, TestSuggestSpellingConverter,
SpellingQueryConverterTest, TestLRUCache, ZkNodePropsTest,
SystemInfoHandlerTest, UUIDFieldTest, CircularListTest,
DistributedMLTComponentTest, CursorPagingTest, SolrTestCaseJ4Test,
TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort,
TestHighlightDedupGrouping, TestTolerantSearch,
TestEmbeddedSolrServerConstructors, AsyncMigrateRouteKeyTest,
CdcrRequestHandlerTest, CloudExitableDirectoryReaderTest,
CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest,
DeleteInactiveReplicaTest, DeleteShardTest, DistribCursorPagingTest,
DistribDocExpirationUpdateProcessorTest, LeaderFailoverAfterPartitionTest,
LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest,
OverriddenZkACLAndCredentialsProvidersTest, OverseerStatusTest,
RemoteQueryErrorTest, ReplicationFactorTest, SaslZkACLProviderTest,
SharedFSAutoReplicaFailoverUtilsTest, SolrCloudExampleTest, SolrXmlInZkTest,
TestCollectionAPI, TestConfigSetsAPI, TestConfigSetsAPIExclusivity,
TestDistribDocBasedVersion, TestDownShardTolerantSearch,
TestMiniSolrCloudClusterBase, TestRandomRequestDistribution,
TestReplicaProperties, VMParamsZkACLAndCredentialsProvidersTest,
HdfsCollectionsAPIDistributedZkTest]
[junit4] Completed [544/544] on J1 in 1421.84s, 1 test, 1 error <<< FAILURES!
[...truncated 13 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:514:
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: 544 suites, 2142 tests, 2 errors, 1 failure, 29
ignored (17 assumptions)
Total time: 191 minutes 59 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
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
