Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/52/
3 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=1094, name=collection4,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=1094, name=collection4, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
at
__randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE:502815C429B16006]:0)
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:55263: collection already exists:
awholynewstresscollection_collection4_5
at __randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1192)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:962)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:898)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1616)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.TestTolerantUpdateProcessorRandomCloud
Error Message:
Could not find collection:test_col
Stack Trace:
java.lang.AssertionError: Could not find collection:test_col
at __randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:150)
at
org.apache.solr.cloud.TestTolerantUpdateProcessorRandomCloud.createMiniSolrCloudCluster(TestTolerantUpdateProcessorRandomCloud.java:135)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Sat Apr 30 07:51:23
ICT 2016
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to
exceed: Sat Apr 30 07:51:23 ICT 2016
at
__randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE:3D72AD88265644D]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1428)
at
org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:780)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11310 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/init-core-data-001
[junit4] 2> 65590 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[D87C2A1E874D0DFE]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 65590 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[D87C2A1E874D0DFE]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 65594 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 65595 INFO (Thread-344) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 65595 INFO (Thread-344) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 65695 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.ZkTestServer start zk server on port:39223
[junit4] 2> 65695 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 65696 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 65714 INFO (zkCallback-60-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@1c45fb97
name:ZooKeeperConnection Watcher:127.0.0.1:39223 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 65714 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 65714 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 65715 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 65718 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 65730 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 65735 INFO (zkCallback-61-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@327c3eb4
name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 65735 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 65735 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 65735 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 65737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 65739 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 65740 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 65741 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 65741 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 65745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 65745 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 65750 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 65751 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 65753 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 65753 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 65755 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 65755 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 65756 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 65756 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 65757 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 65758 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 65759 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 65759 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 65761 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 65761 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 65765 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 65765 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 65767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 65767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 65878 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1
[junit4] 2> 65880 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 65882 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@23bfc24b{/,null,AVAILABLE}
[junit4] 2> 65885 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@7ec9621e{HTTP/1.1,[http/1.1]}{127.0.0.1:53731}
[junit4] 2> 65885 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.e.j.s.Server Started @68824ms
[junit4] 2> 65885 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/tempDir-001/control/data,
hostContext=/, hostPort=53731,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores}
[junit4] 2> 65885 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 65885 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001'
[junit4] 2> 65885 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 65885 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 65886 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 65886 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 65889 INFO (zkCallback-62-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@222ef9fb
name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 65889 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 65890 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 65892 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 65892 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/solr.xml
[junit4] 2> 65899 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores
[junit4] 2> 65899 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.CoreContainer New CoreContainer 3858867
[junit4] 2> 65899 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001]
[junit4] 2> 65900 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/lib
[junit4] 2> 65900 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
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> 65901 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 65902 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 65902 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 65902 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39223/solr
[junit4] 2> 65902 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 65902 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 65903 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 65906 INFO (zkCallback-65-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3ddf3a9b
name:ZooKeeperConnection Watcher:127.0.0.1:39223 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 65906 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 65907 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 65909 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.ZkController Added new OnReconnect listener
org.apache.solr.cloud.ZkController$$Lambda$38/887802213@2304a10c
[junit4] 2> 65910 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 65912 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4c9e88c8
name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 65912 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 65915 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 65917 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 65919 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 65921 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 65922 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 65924 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 65927 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 65928 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 65929 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 65930 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 65931 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (0)
[junit4] 2> 65932 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 65933 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 65934 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 65935 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/95812075095588868-127.0.0.1:53731_-n_0000000000
[junit4] 2> 65935 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:53731_
[junit4] 2> 65935 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 65936 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.Overseer Overseer
(id=95812075095588868-127.0.0.1:53731_-n_0000000000) starting
[junit4] 2> 65938 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 65943 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 65944 INFO
(OverseerCollectionConfigSetProcessor-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.OverseerTaskProcessor Process current queue
of overseer operations
[junit4] 2> 65944 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 65944 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53731_
[junit4] 2> 65945 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:53731_
[junit4] 2> 65946 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [0])
[junit4] 2> 65946 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 65949 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1)
[junit4] 2> 65971 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 65971 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 65972 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores
[junit4] 2> 65973 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
collection=control_collection, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 65973 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.CorePropertiesLocator Found core collection1
in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1
[junit4] 2> 65973 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 65974 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1
[junit4] 2> 65974 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:53731_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 65974 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 65974 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 65976 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController
Collection control_collection not visible yet, but flagging it so a watch is
registered when it becomes visible
[junit4] 2> 65976 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader addZkWatch [control_collection]
[junit4] 2> 65976 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] 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:53731";,
[junit4] 2> "node_name":"127.0.0.1:53731_",
[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> 65977 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] 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:53731";,
[junit4] 2> "node_name":"127.0.0.1:53731_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 65977 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
control_collection
[junit4] 2> 65977 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
[junit4] 2> 65978 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 65979 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 65980 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
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> 66979 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 66980 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController
Check for collection zkNode:control_collection
[junit4] 2> 66980 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 66980 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 66981 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 66981 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1'
[junit4] 2> 66981 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 66981 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 66983 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 66996 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 67002 WARN
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 67003 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.1.0
[junit4] 2> 67012 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 67018 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 67111 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 67117 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.IndexSchema
default search field in schema is text
[junit4] 2> 67118 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.s.IndexSchema
unique key field: id
[junit4] 2> 67132 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 67134 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 67136 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 67137 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 67142 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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-6.x/solr/build/solr-core/test/J1
[junit4] 2> 67142 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 67143 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 67143 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/]
[junit4] 2> 67143 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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@3a7ad154
[junit4] 2> 67144 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data
[junit4] 2> 67145 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/index/
[junit4] 2> 67145 WARN
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 67145 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/index
[junit4] 2> 67145 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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=6, maxMergedSegmentMB=77.5712890625,
floorSegmentMB=1.3564453125, forceMergeDeletesPctAllowed=8.889765143520206,
segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7228485563731526
[junit4] 2> 67146 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ecab59b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c7ce6fe),segFN=segments_1,generation=1}
[junit4] 2> 67146 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 67148 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53731_
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-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/
[junit4] 2> 67149 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.DirectoryFactory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 67152 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 67152 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 67152 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 67152 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 67153 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 67153 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67153 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67153 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67153 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67153 WARN
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 67155 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 67156 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 67157 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 67158 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 67162 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 67162 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67162 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67162 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67163 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 67163 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 67165 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8426334006169524]
[junit4] 2> 67165 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ecab59b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c7ce6fe),segFN=segments_1,generation=1}
[junit4] 2> 67165 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 67165 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@30712c90[collection1] main]
[junit4] 2> 67165 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67168 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67168 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67168 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 67169 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 67169 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67169 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67169 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67169 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 67169 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 67170 INFO
(searcherExecutor-293-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@30712c90[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 67171 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67171 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67171 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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> 67171 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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 1532993203175686144
[junit4] 2> 67172 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ 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
1532993203175686144
[junit4] 2> 67172 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 67173 INFO
(coreLoadExecutor-292-thread-1-processing-n:127.0.0.1:53731_)
[n:127.0.0.1:53731_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 67173 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
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:53731
collection:control_collection shard:shard1
[junit4] 2> 67174 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
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> 67178 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
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/95812075095588868-core_node1-n_0000000000
[junit4] 2> 67179 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1
and weAreReplacement=false and leaderVoteWait=180000
[junit4] 2> 67181 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 67181 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
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> 67181 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:53731/collection1/
[junit4] 2> 67181 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 67181 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:53731/collection1/ has no replicas
[junit4] 2> 67182 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 67184 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/control_collection/leaders/shard1/leader after winning as
/collections/control_collection/leader_elect/shard1/election/95812075095588868-core_node1-n_0000000000
[junit4] 2> 67184 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] 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> 67188 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
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:53731/collection1/ shard1
[junit4] 2> 67188 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] 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:53731";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 67291 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
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> 67339 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are http://127.0.0.1:53731/collection1/ and leader is
http://127.0.0.1:53731/collection1/
[junit4] 2> 67339 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
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:53731
[junit4] 2> 67339 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 67340 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 67340 INFO
(coreZkRegister-286-thread-1-processing-n:127.0.0.1:53731_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53731_
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> 67342 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] 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:53731";,
[junit4] 2> "node_name":"127.0.0.1:53731_",
[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> 67343 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] 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:53731";,
[junit4] 2> "node_name":"127.0.0.1:53731_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 67446 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
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> 67476 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67477 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67479 INFO (zkCallback-68-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7c3d14d8
name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67479 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67479 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67479 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 67485 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 67486 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 67595 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores/collection1
[junit4] 2> 67595 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001
[junit4] 2> 67596 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 67598 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2f928fd0{/,null,AVAILABLE}
[junit4] 2> 67598 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@48131c0d{HTTP/1.1,[http/1.1]}{127.0.0.1:40003}
[junit4] 2> 67599 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.e.j.s.Server Started @70538ms
[junit4] 2> 67599 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=40003,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores}
[junit4] 2> 67599 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 67599 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001'
[junit4] 2> 67599 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 67599 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 67600 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67600 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67606 INFO (zkCallback-69-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2930bb33
name:ZooKeeperConnection Watcher:127.0.0.1:39223/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67606 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67606 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67610 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 67610 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/solr.xml
[junit4] 2> 67615 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores
[junit4] 2> 67615 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1650000496
[junit4] 2> 67615 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001]
[junit4] 2> 67616 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/lib
[junit4] 2> 67616 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
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> 67617 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 67618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 67618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 67618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39223/solr
[junit4] 2> 67618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 67618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67623 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67628 INFO (zkCallback-72-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@42eff37a
name:ZooKeeperConnection Watcher:127.0.0.1:39223 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67629 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67630 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67631 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.ZkController Added new OnReconnect listener
org.apache.solr.cloud.ZkController$$Lambda$38/887802213@6c184750
[junit4] 2> 67636 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 67643 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6852de2 name:ZooKeeperConnection
Watcher:127.0.0.1:39223/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 67643 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 67649 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 67650 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 67652 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 67652 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/95812075095588872-127.0.0.1:40003_-n_0000000001
[junit4] 2> 67653 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/95812075095588868-127.0.0.1:53731_-n_0000000000 to
know if I could be the leader
[junit4] 2> 67653 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.ZkController Publish node=127.0.0.1:40003_ as
DOWN
[junit4] 2> 67655 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:40003_"} current state version: 3
[junit4] 2> 67655 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node:
127.0.0.1:40003_
[junit4] 2> 67758 INFO (zkCallback-68-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> 67758 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
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> 67758 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ]
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> 68655 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40003_
[junit4] 2> 68656 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:40003_
[junit4] 2> 68658 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ]
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> 68660 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
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> 68660 INFO (zkCallback-68-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> 68660 INFO (zkCallback-68-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 68660 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 68668 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 68669 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 68670 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 68670 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores
[junit4] 2> 68671 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
collection=collection1, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 68671 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.CorePropertiesLocator Found core collection1
in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D87C2A1E874D0DFE-001/shard-1-001/cores/collection1
[junit4] 2> 68671 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 68673 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1
[junit4] 2> 68673 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[D87C2A1E874D0DFE])
[n:127.0.0.1:40003_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 68674 INFO
(coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_)
[n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 68674 INFO
(coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_)
[n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 68675 INFO
(coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_)
[n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController
Collection collection1 not visible yet, but flagging it so a watch is
registered when it becomes visible
[junit4] 2> 68675 INFO
(coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_)
[n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
addZkWatch [collection1]
[junit4] 2> 68675 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] 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:40003";,
[junit4] 2> "node_name":"127.0.0.1:40003_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 68675 INFO
(coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_)
[n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
Deleting data for [collection1]
[junit4] 2> 68675 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] 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:40003";,
[junit4] 2> "node_name":"127.0.0.1:40003_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 68675 INFO
(coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_)
[n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController look
for our core node name
[junit4] 2> 68676 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
collection1
[junit4] 2> 68676 INFO
(OverseerStateUpdate-95812075095588868-127.0.0.1:53731_-n_0000000000)
[n:127.0.0.1:53731_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard2
[junit4] 2> 68778 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:40003_) [n:127.0.0.1:40003_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [2])
[junit4] 2> 68778 INFO
(zkCallback-66-thread-1-processing-n:127.0.0.1:53731_) [n:127.0.0.1:53731_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [2])
[junit4] 2> 68778 INFO (zkCallback-68-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [2])
[junit4] 2> 69676 INFO
(coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_)
[n:127.0.0.1:40003_ c:collection1 x:collection1] o.a.s.c.ZkController waiting
to find shard id in clusterstate for collection1
[junit4] 2> 69676 INFO
(coreLoadExecutor-303-thread-1-processing-n:127.0.0.1:40003_)
[n:127.0.0.1:40003_ c:collection1 x:colle
[...truncated too long message...]
ess$200(LeaderElector.java:56)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:266)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3930538 WARN
(zkCallback-3794-thread-2-processing-n:127.0.0.1:39387_solr)
[n:127.0.0.1:39387_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 3930536 WARN
(zkCallback-3817-thread-2-processing-n:127.0.0.1:42884_solr)
[n:127.0.0.1:42884_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 3930538 WARN
(zkCallback-3815-thread-2-processing-n:127.0.0.1:46941_solr)
[n:127.0.0.1:46941_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 3930537 INFO (jetty-launcher-3775-thread-13) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3359f584{/solr,null,UNAVAILABLE}
[junit4] 2> 3930539 INFO (jetty-launcher-3775-thread-7) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2883f82c{/solr,null,UNAVAILABLE}
[junit4] 2> 3930539 INFO (jetty-launcher-3775-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@654d6eda{/solr,null,UNAVAILABLE}
[junit4] 2> 3930539 INFO (jetty-launcher-3775-thread-8) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@78bf24a3{/solr,null,UNAVAILABLE}
[junit4] 2> 3930540 INFO (jetty-launcher-3775-thread-6) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3fb36723{/solr,null,UNAVAILABLE}
[junit4] 2> 3930543 INFO
(SUITE-TestTolerantUpdateProcessorRandomCloud-seed#[D87C2A1E874D0DFE]-worker) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34152 34152
[junit4] 2> 3930721 INFO (Thread-100393) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:34152 34152
[junit4] 2> 3930722 WARN (Thread-100393) [ ] o.a.s.c.ZkTestServer
Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/aliases.json
[junit4] 2> 13 /solr/security.json
[junit4] 2> 11
/solr/configs/solrCloudCollectionConfig/managed-schema
[junit4] 2> 11 /solr/configs/solrCloudCollectionConfig
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/clusterstate.json
[junit4] 2> 12 /solr/collections/test_col/state.json
[junit4] 2> 2
/solr/collections/test_col/leader_elect/shard2/election/95812325951733789-core_node11-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 14 /solr/collections
[junit4] 2>
[junit4] 2> 3930722 INFO
(SUITE-TestTolerantUpdateProcessorRandomCloud-seed#[D87C2A1E874D0DFE]-worker) [
] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> Apr 30, 2016 1:35:08 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are:
codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION,
chunkSize=20452, maxDocsPerChunk=3, blockSize=31),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION,
chunkSize=20452, blockSize=31)), sim=ClassicSimilarity, locale=sk,
timezone=Etc/GMT-7
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_74 (64-bit)/cpus=4,threads=1,free=199675744,total=515375104
[junit4] 2> NOTE: All tests run in this JVM: [SolrCmdDistributorTest,
SolrIndexSplitterTest, TestRequestStatusCollectionAPI, TimeZoneUtilsTest,
SuggestComponentTest, DirectSolrSpellCheckerTest, TestTrie,
CollectionsAPIDistributedZkTest, HdfsBasicDistributedZkTest,
SoftAutoCommitTest, TestQueryUtils, AddSchemaFieldsUpdateProcessorFactoryTest,
TestExclusionRuleCollectionAccess, TestCustomSort, DeleteReplicaTest,
SpellingQueryConverterTest, TestAnalyzeInfixSuggestions, TestJsonFacets,
CSVRequestHandlerTest, TriLevelCompositeIdRoutingTest,
TestDynamicFieldCollectionResource, TestLFUCache, CloudMLTQParserTest,
TolerantUpdateProcessorTest, TestSolrQueryResponse, TestUseDocValuesAsStored,
TestSimpleTrackingShardHandler, TestObjectReleaseTracker, TestMacros,
TestTestInjection, TestSolr4Spatial2, TestCoreContainer,
TestPartialUpdateDeduplication, SecurityConfHandlerTest,
TestReloadAndDeleteDocs, CollectionsAPIAsyncDistributedZkTest,
DistributedExpandComponentTest, NumericFieldsTest, TestDFISimilarityFactory,
TestLRUStatsCache, ShardRoutingCustomTest, SolrInfoMBeanTest, TestCodecSupport,
TestSchemaVersionResource, TestSolrConfigHandlerCloud, ZkStateReaderTest,
BasicFunctionalityTest, SolrIndexConfigTest, CdcrUpdateLogTest,
SpellCheckCollatorTest, TestConfigOverlay, AliasIntegrationTest,
TestMissingGroups, CustomCollectionTest, CollectionReloadTest,
TestQuerySenderNoQuery, DocValuesTest, RecoveryAfterSoftCommitTest,
UniqFieldsUpdateProcessorFactoryTest, FastVectorHighlighterTest,
TestCloudInspectUtil, TestSolrDeletionPolicy2, LoggingHandlerTest,
SaslZkACLProviderTest, TestAuthorizationFramework, HighlighterTest,
DistribCursorPagingTest, FileUtilsTest, TestSortByMinMaxFunction,
TestHashPartitioner, TestManagedSynonymFilterFactory, TestHashQParserPlugin,
FacetPivotSmallTest, SchemaVersionSpecificBehaviorTest, ConvertedLegacyTest,
TestDFRSimilarityFactory, BlockJoinFacetRandomTest, TestBinaryResponseWriter,
DateRangeFieldTest, TestFreeTextSuggestions, HighlighterMaxOffsetTest,
TestWordDelimiterFilterFactory, TestBlobHandler, TestSurroundQueryParser,
NotRequiredUniqueKeyTest, OutOfBoxZkACLAndCredentialsProvidersTest,
TestSchemaNameResource, HdfsThreadLeakTest, DocumentBuilderTest,
TestGeoJSONResponseWriter, SpatialFilterTest, AnalyticsQueryTest,
TermsComponentTest, HdfsCollectionsAPIDistributedZkTest, RuleEngineTest,
MoreLikeThisHandlerTest, OverseerTest, RecoveryZkTest, ZkSolrClientTest,
TestZkChroot, ZkCLITest, TestDistributedSearch, TestCursorMarkWithoutUniqueKey,
TestDistributedMissingSort, TestEmbeddedSolrServerConstructors,
TestJettySolrRunner, AsyncMigrateRouteKeyTest, CdcrReplicationHandlerTest,
CollectionTooManyReplicasTest, ConcurrentDeleteAndCreateCollectionTest,
DeleteStatusTest, DistributedQueueTest, HttpPartitionTest,
LeaderInitiatedRecoveryOnShardRestartTest, RemoteQueryErrorTest,
ShardSplitTest, TestOnReconnectListenerSupport, TestRebalanceLeaders,
TestTolerantUpdateProcessorRandomCloud]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestTolerantUpdateProcessorRandomCloud -Dtests.seed=D87C2A1E874D0DFE
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=sk -Dtests.timezone=Etc/GMT-7 -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestTolerantUpdateProcessorRandomCloud (suite)
<<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not find
collection:test_col
[junit4] > at
__randomizedtesting.SeedInfo.seed([D87C2A1E874D0DFE]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:150)
[junit4] > at
org.apache.solr.cloud.TestTolerantUpdateProcessorRandomCloud.createMiniSolrCloudCluster(TestTolerantUpdateProcessorRandomCloud.java:135)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [474/592 (3!)] on J1 in 37.89s, 0 tests, 1 failure <<<
FAILURES!
[...truncated 385 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014:
There were test failures: 592 suites (10 ignored), 2397 tests, 1 suite-level
error, 1 error, 1 failure, 31 ignored (16 assumptions) [seed: D87C2A1E874D0DFE]
Total time: 253 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
