Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/12/
3 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 2 object(s) that were not released!!! [NRTCachingDirectory,
NRTCachingDirectory]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 2 object(s) that were not
released!!! [NRTCachingDirectory, NRTCachingDirectory]
at __randomizedtesting.SeedInfo.seed([78DC986344E9E28]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228)
at sun.reflect.GeneratedMethodAccessor27.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
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.search.TestIndexSearcher.testReopen
Error Message:
expected:<1> but was:<6>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<6>
at
__randomizedtesting.SeedInfo.seed([78DC986344E9E28:2BC518904772110B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.search.TestIndexSearcher.testReopen(TestIndexSearcher.java:161)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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)
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=1005, name=collection5,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=1005, name=collection5, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:53752/d/h: collection already exists:
awholynewstresscollection_collection5_0
at __randomizedtesting.SeedInfo.seed([78DC986344E9E28]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1575)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1596)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:984)
Build Log:
[...truncated 11199 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/init-core-data-001
[junit4] 2> 34926 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[78DC986344E9E28]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 34926 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[78DC986344E9E28]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /d/h
[junit4] 2> 34942 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 34943 INFO (Thread-4) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 34944 INFO (Thread-4) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 35045 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.ZkTestServer start zk server on port:53400
[junit4] 2> 35057 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 35085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 35110 INFO (zkCallback-3-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@31fd4b0b
name:ZooKeeperConnection Watcher:127.0.0.1:53400 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 35110 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 35110 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 35112 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 35130 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 35131 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15483a26dc50000, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 35132 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 35134 INFO (zkCallback-4-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@566d133 name:ZooKeeperConnection
Watcher:127.0.0.1:53400/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 35134 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 35135 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 35137 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 35141 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 35144 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 35146 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 35149 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 35154 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 35159 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 35159 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 35162 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 35162 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 35165 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 35165 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 35168 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 35168 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 35171 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 35171 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 35174 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 35174 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 35177 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 35177 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 35180 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 35180 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 35183 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 35183 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 35191 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 35191 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 35280 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1
[junit4] 2> 35302 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.u.log Logging initialized @37277ms
[junit4] 2> 35404 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 35446 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5e546ed6{/d/h,null,AVAILABLE}
[junit4] 2> 35452 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@213bae14{HTTP/1.1}{127.0.0.1:53752}
[junit4] 2> 35452 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.s.Server Started @37428ms
[junit4] 2> 35452 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/tempDir-001/control/data,
hostContext=/d/h, hostPort=53752,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores}
[junit4] 2> 35457 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 35458 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001'
[junit4] 2> 35458 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 35458 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 35458 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 35459 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 35461 INFO (zkCallback-5-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5c8ef1a6
name:ZooKeeperConnection Watcher:127.0.0.1:53400/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 35461 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 35461 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 35467 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 35474 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/solr.xml
[junit4] 2> 35493 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores
[junit4] 2> 35493 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1245535326
[junit4] 2> 35493 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001]
[junit4] 2> 35493 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/lib
[junit4] 2> 35501 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
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> 35502 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 35503 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 35503 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 35503 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53400/solr
[junit4] 2> 35510 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 35510 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 35511 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 35513 INFO (zkCallback-8-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@134a68cc
name:ZooKeeperConnection Watcher:127.0.0.1:53400 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 35513 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 35513 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 35516 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.ZkController Added new OnReconnect
listener org.apache.solr.cloud.ZkController$6@ed73f64
[junit4] 2> 35518 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 35519 INFO
(zkCallback-9-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@a2a4411 name:ZooKeeperConnection
Watcher:127.0.0.1:53400/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 35519 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 35524 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 35531 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 35536 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 35539 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 35542 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 35549 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 35551 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 35553 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/clusterstate.json
[junit4] 2> 35554 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 35556 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53752_d%2Fh
[junit4] 2> 35557 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:53752_d%2Fh
[junit4] 2> 35562 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 35564 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 35565 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 35566 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.LeaderElector Joined leadership election
with path:
/overseer_elect/election/95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000
[junit4] 2> 35569 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:53752_d%2Fh
[junit4] 2> 35569 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/leader
[junit4] 2> 35571 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.Overseer Overseer
(id=95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000) starting
[junit4] 2> 35577 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/overseer/queue-work
[junit4] 2> 35599 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.OverseerAutoReplicaFailoverThread
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 35642 INFO
(OverseerCollectionConfigSetProcessor-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.OverseerTaskProcessor Process current
queue of overseer operations
[junit4] 2> 35642 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 35646 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.Overseer Starting to work on the main
queue
[junit4] 2> 35778 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.CoreContainer Security conf doesn't
exist. Skipping setup for authorization module.
[junit4] 2> 35778 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.CoreContainer No authentication plugin
used.
[junit4] 2> 35779 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores
[junit4] 2> 35782 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{collection=control_collection, configSetProperties=configsetprops.json,
transient=false, config=solrconfig.xml, coreNodeName=, shard=,
name=collection1, dataDir=data/, schema=schema.xml, loadOnStartup=true}
[junit4] 2> 35782 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.CorePropertiesLocator Found core
collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1
[junit4] 2> 35782 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions
[junit4] 2> 35785 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
[junit4] 2> 35785 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:53752_d%2Fh ] o.a.s.s.SolrDispatchFilter
SolrDispatchFilter.init() done
[junit4] 2> 35785 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.ZkController publishing state=down
[junit4] 2> 35785 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.ZkController numShards not found on descriptor - reading it from system
property
[junit4] 2> 35790 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 35790 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader addZkWatch [control_collection]
[junit4] 2> 35791 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "node_name":"127.0.0.1:53752_d%2Fh",
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:53752/d/h";,
[junit4] 2> "roles":null} current state version: 0
[junit4] 2> 35797 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "node_name":"127.0.0.1:53752_d%2Fh",
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:53752/d/h";,
[junit4] 2> "roles":null}
[junit4] 2> 35798 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
[junit4] 2> 35800 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.ZkController look for our core node name
[junit4] 2> 35800 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.o.ClusterStateMutator building a new
cName: control_collection
[junit4] 2> 35807 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 35811 INFO
(zkCallback-9-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh ] 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> 36800 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 36800 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 36801 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 36801 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 36802 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 36803 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1'
[junit4] 2> 36803 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 36803 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 36806 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1] o.a.s.c.Config
loaded config solrconfig.xml with version 0
[junit4] 2> 36832 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 36854 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1
[junit4] 2> 36920 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 37012 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 37511 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 37550 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 37551 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 37642 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 37653 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 37662 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 37673 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 37680 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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-5.5/solr/build/solr-core/test/J1
[junit4] 2> 37680 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 37680 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 37680 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1/data/]
[junit4] 2> 37684 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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@51205cb7
[junit4] 2> 37687 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1/data
[junit4] 2> 37687 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1/data/index/
[junit4] 2> 37687 WARN
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 37687 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1/data/index
[junit4] 2> 37687 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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=28, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=71.97265625,
floorSegmentMB=0.376953125, forceMergeDeletesPctAllowed=0.6832428717289696,
segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 37689 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits:
num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@47c2b6e3
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@407515e3),segFN=segments_1,generation=1}
[junit4] 2> 37689 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 37689 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53752_d%2Fh
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-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1/data/
[junit4] 2> 37690 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53752_d%2Fh
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.DirectoryFactory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 37698 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 37710 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 37712 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 37712 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 37712 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 37712 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37733 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37733 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37733 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37733 WARN
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37735 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 37738 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 37739 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 37741 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 37757 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/ping,/admin/logging,standard,/update,/replication,/admin/system,/admin/plugins,/update/json/docs,/admin/luke,/dump,/admin/file,/admin/properties,/admin/threads,/admin/mbeans,/config,/admin/segments,/update/csv,/get,/update/json,/schema
[junit4] 2> 37759 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37765 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37765 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37771 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 37771 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 37772 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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=50, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=82.763671875,
floorSegmentMB=0.537109375, forceMergeDeletesPctAllowed=10.219943218976024,
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6500795579739098
[junit4] 2> 37773 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits:
num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@47c2b6e3
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@407515e3),segFN=segments_1,generation=1}
[junit4] 2> 37773 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 37773 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@952f842[collection1]
main
[junit4] 2> 37774 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37775 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37775 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37777 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 37777 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 37777 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37777 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37778 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37778 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 37781 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 37785 INFO
(searcherExecutor-21-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@952f842[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 37788 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37788 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37788 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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> 37788 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh 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 1533539614635065344
[junit4] 2> 37792 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Took 3.0ms to seed version buckets with
highest version 1533539614635065344
[junit4] 2> 37793 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 37796 INFO
(coreLoadExecutor-20-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 37797 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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:53752/d/h collection:control_collection shard:shard1
[junit4] 2> 37803 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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> 37809 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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/95846225731190788-core_node1-n_0000000000
[junit4] 2> 37809 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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> 37812 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 37813 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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> 37813 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] 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> 37813 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:53752/d/h/collection1/
[junit4] 2> 37813 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 37813 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:53752/d/h/collection1/ has
no replicas
[junit4] 2> 37816 INFO
(zkCallback-9-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh ] 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> 37823 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.c.SolrZkClient makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 37844 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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/95846225731190788-core_node1-n_0000000000
[junit4] 2> 37864 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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:53752/d/h/collection1/ shard1
[junit4] 2> 37866 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] 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:53752/d/h";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 37970 INFO
(zkCallback-9-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh ] 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> 38016 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController We are
http://127.0.0.1:53752/d/h/collection1/ and leader is
http://127.0.0.1:53752/d/h/collection1/
[junit4] 2> 38017 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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:53752/d/h
[junit4] 2> 38017 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 38017 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 38017 INFO
(coreZkRegister-14-thread-1-processing-n:127.0.0.1:53752_d%2Fh
c:control_collection x:collection1 s:shard1 r:core_node1)
[n:127.0.0.1:53752_d%2Fh 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> 38020 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "node_name":"127.0.0.1:53752_d%2Fh",
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:53752/d/h";,
[junit4] 2> "roles":null} current state version: 3
[junit4] 2> 38021 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "node_name":"127.0.0.1:53752_d%2Fh",
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "shard":"shard1",
[junit4] 2> "base_url":"http://127.0.0.1:53752/d/h";,
[junit4] 2> "roles":null}
[junit4] 2> 38126 INFO
(zkCallback-9-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh ] 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> 38304 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 38305 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 38307 INFO (zkCallback-11-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@1007d5f5
name:ZooKeeperConnection Watcher:127.0.0.1:53400/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 38307 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 38307 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 38307 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 38315 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 38381 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/cores/collection1
[junit4] 2> 38381 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001
[junit4] 2> 38382 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 38384 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@32e5e4ff{/d/h,null,AVAILABLE}
[junit4] 2> 38385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@1378e54f{HTTP/1.1}{127.0.0.1:57062}
[junit4] 2> 38385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.e.j.s.Server Started @40361ms
[junit4] 2> 38385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/cores,
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/tempDir-001/jetty1,
hostPort=57062, solrconfig=solrconfig.xml, hostContext=/d/h}
[junit4] 2> 38385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 38385 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001'
[junit4] 2> 38386 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 38386 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 38386 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 38386 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 38388 INFO (zkCallback-12-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3a1e3bc2
name:ZooKeeperConnection Watcher:127.0.0.1:53400/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 38388 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 38388 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 38390 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 38390 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/solr.xml
[junit4] 2> 38409 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/cores
[junit4] 2> 38409 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1786014777
[junit4] 2> 38409 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001]
[junit4] 2> 38410 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/lib
[junit4] 2> 38410 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
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> 38411 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 38411 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 38411 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 38412 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53400/solr
[junit4] 2> 38412 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 38412 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 38413 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 38414 INFO (zkCallback-15-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5692dfe3
name:ZooKeeperConnection Watcher:127.0.0.1:53400 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 38414 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 38414 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 38416 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.ZkController Added new OnReconnect
listener org.apache.solr.cloud.ZkController$6@526db62d
[junit4] 2> 38416 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 38434 INFO
(zkCallback-16-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5fa69521
name:ZooKeeperConnection Watcher:127.0.0.1:53400/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 38434 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 38442 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 39452 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:57062_d%2Fh
[junit4] 2> 39452 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:57062_d%2Fh
[junit4] 2> 39454 INFO (zkCallback-11-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> 39454 INFO
(zkCallback-9-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh ] 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> 39454 INFO
(zkCallback-16-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh ] 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> 39457 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 39458 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.LeaderElector Joined leadership election
with path:
/overseer_elect/election/95846225731190792-127.0.0.1:57062_d%2Fh-n_0000000001
[junit4] 2> 39459 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000
to know if I could be the leader
[junit4] 2> 39477 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.CoreContainer Security conf doesn't
exist. Skipping setup for authorization module.
[junit4] 2> 39477 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.CoreContainer No authentication plugin
used.
[junit4] 2> 39478 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/cores
[junit4] 2> 39479 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{schema=schema.xml, coreNodeName=, configSetProperties=configsetprops.json,
shard=, transient=false, config=solrconfig.xml, loadOnStartup=true,
dataDir=data/, name=collection1, collection=collection1}
[junit4] 2> 39479 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.CorePropertiesLocator Found core
collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/cores/collection1
[junit4] 2> 39479 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions
[junit4] 2> 39483 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
[junit4] 2> 39483 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[78DC986344E9E28])
[n:127.0.0.1:57062_d%2Fh ] o.a.s.s.SolrDispatchFilter
SolrDispatchFilter.init() done
[junit4] 2> 39490 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 39491 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 39493 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh 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> 39493 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
addZkWatch [collection1]
[junit4] 2> 39494 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "node_name":"127.0.0.1:57062_d%2Fh",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:57062/d/h";,
[junit4] 2> "roles":null} current state version: 4
[junit4] 2> 39494 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
Deleting data for [collection1]
[junit4] 2> 39495 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.o.ReplicaMutator Update state numShards=2
message={
[junit4] 2> "node_name":"127.0.0.1:57062_d%2Fh",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "operation":"state",
[junit4] 2> "shard":null,
[junit4] 2> "base_url":"http://127.0.0.1:57062/d/h";,
[junit4] 2> "roles":null}
[junit4] 2> 39495 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.o.ClusterStateMutator building a new
cName: collection1
[junit4] 2> 39495 INFO
(OverseerStateUpdate-95846225731190788-127.0.0.1:53752_d%2Fh-n_0000000000)
[n:127.0.0.1:53752_d%2Fh ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 39496 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 39599 INFO (zkCallback-11-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> 39599 INFO
(zkCallback-16-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh ] 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> 39599 INFO
(zkCallback-9-thread-1-processing-n:127.0.0.1:53752_d%2Fh)
[n:127.0.0.1:53752_d%2Fh ] 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> 40496 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 40497 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.ZkController
Check for collection zkNode:collection1
[junit4] 2> 40497 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 40497 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
Load collection config from: [/collections/collection1]
[junit4] 2> 40498 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.c.ZkStateReader
path=[/collections/collection1] [configName]=[conf1] specified config exists in
ZooKeeper
[junit4] 2> 40498 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_78DC986344E9E28-001/shard-1-001/cores/collection1'
[junit4] 2> 40499 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 40499 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 40500 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 40514 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 40526 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 5.5.1
[junit4] 2> 40552 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 40626 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 40934 INFO
(coreLoadExecutor-31-thread-1-processing-n:127.0.0.1:57062_d%2Fh)
[n:127.0.0.1:57062_d%2Fh c:collection1 x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4]
[...truncated too long message...]
expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2489977 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 2489978 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached
0 - closing IndexWriter
[junit4] 2> 2489978 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with
IndexWriterCloser
[junit4] 2> 2489978 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 2489978 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2
directories currently being tracked
[junit4] 2> 2489978 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_78DC986344E9E28-001/solr-instance-027/./collection1/data/index
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_78DC986344E9E28-001/solr-instance-027/./collection1/data/index;done=false>>]
[junit4] 2> 2489978 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_78DC986344E9E28-001/solr-instance-027/./collection1/data/index
[junit4] 2> 2489978 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_78DC986344E9E28-001/solr-instance-027/./collection1/data
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_78DC986344E9E28-001/solr-instance-027/./collection1/data;done=false>>]
[junit4] 2> 2489978 INFO (coreCloseExecutor-5415-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_78DC986344E9E28-001/solr-instance-027/./collection1/data
[junit4] 2> 2489978 INFO
(TEST-TestReplicationHandler.doTestDetails-seed#[78DC986344E9E28]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@69d4b838{/solr,null,UNAVAILABLE}
[junit4] 2> 2489979 INFO
(SUITE-TestReplicationHandler-seed#[78DC986344E9E28]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> ObjectTracker found 2 object(s) that were not released!!!
[NRTCachingDirectory, NRTCachingDirectory]
[junit4] 2>
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:368)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:258)
[junit4] 2> at
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:385)
[junit4] 2> at
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:273)
[junit4] 2>
[junit4] 2>
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:368)
[junit4] 2> at
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:258)
[junit4] 2> at
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:385)
[junit4] 2> at
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:273)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54):
{newname=PostingsFormat(name=Memory doPackFST= false),
id=PostingsFormat(name=Memory doPackFST= false),
name=PostingsFormat(name=Memory doPackFST= false)}, docValues:{},
sim=DefaultSimilarity, locale=es-PR, timezone=America/Argentina/Rio_Gallegos
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.7.0_80 (64-bit)/cpus=4,threads=1,free=96511672,total=525336576
[junit4] 2> NOTE: All tests run in this JVM: [CopyFieldTest,
CollectionsAPISolrJTest, StandardRequestHandlerTest,
TestBlendedInfixSuggestions, TestSchemaSimilarityResource, TestConfig,
IndexSchemaRuntimeFieldTest, SOLR749Test, DocumentAnalysisRequestHandlerTest,
CurrencyFieldOpenExchangeTest, ActionThrottleTest, HdfsRecoveryZkTest,
DateFieldTest, CloudMLTQParserTest, DistribDocExpirationUpdateProcessorTest,
AnalyticsMergeStrategyTest, HdfsThreadLeakTest, InfoHandlerTest,
TestMissingGroups, BufferStoreTest, TestDocBasedVersionConstraints,
RollingRestartTest, TestStressLucene, CloneFieldUpdateProcessorFactoryTest,
TestSolrQueryResponse, DeleteShardTest, DistributedFacetPivotWhiteBoxTest,
TestDistributedSearch, TestComplexPhraseQParserPlugin, TestLuceneMatchVersion,
AsyncCallRequestStatusResponseTest, TestRTimerTree, SystemInfoHandlerTest,
TestFastOutputStream, TestInitParams, TestBinaryField, TestSolrXml,
SignatureUpdateProcessorFactoryTest, ParsingFieldUpdateProcessorsTest,
ConnectionReuseTest, TestExactSharedStatsCache, TestRangeQuery,
TestIBSimilarityFactory, TestComponentsName, TestManagedResourceStorage,
BlockJoinFacetDistribTest, TestPseudoReturnFields,
TestDynamicFieldCollectionResource, SolrRequestParserTest, TestRemoteStreaming,
TestCustomDocTransformer, QueryElevationComponentTest, TestRandomFaceting,
ReturnFieldsTest, TestScoreJoinQPNoScore, CursorPagingTest, TestXmlQParser,
SynonymTokenizerTest, TestRestoreCore, ChangedSchemaMergeTest,
TestConfigSetsAPIExclusivity, URLClassifyProcessorTest, TestCSVLoader,
TestFieldResource, SuggestComponentContextFilterQueryTest, TestOmitPositions,
TestMergePolicyConfig, TestStandardQParsers, TestBadConfig, TestLazyCores,
PathHierarchyTokenizerFactoryTest, TestSimpleQParserPlugin, TestDynamicLoading,
RequiredFieldsTest, TestOnReconnectListenerSupport, TestDFISimilarityFactory,
IndexSchemaTest, TestClassNameShortening, TestMiniSolrCloudClusterBase,
ZkNodePropsTest, TestManagedSchemaDynamicFieldResource, TestExpandComponent,
FileBasedSpellCheckerTest, AnalysisAfterCoreReloadTest, OutputWriterTest,
StressHdfsTest, PeerSyncTest, PolyFieldTest, CheckHdfsIndexTest, TestDocSet,
ResourceLoaderTest, HLLUtilTest, DefaultValueUpdateProcessorTest,
QueryEqualityTest, CoreAdminCreateDiscoverTest, EnumFieldTest,
BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test,
SyncSliceTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest,
TestReplicationHandler]
[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=TestReplicationHandler -Dtests.seed=78DC986344E9E28
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=es-PR -Dtests.timezone=America/Argentina/Rio_Gallegos
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestReplicationHandler (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 2
object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory]
[junit4] > at
__randomizedtesting.SeedInfo.seed([78DC986344E9E28]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [423/572 (3!)] on J0 in 618.55s, 13 tests, 1 failure <<<
FAILURES!
[...truncated 498 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033:
There were test failures: 572 suites (7 ignored), 2293 tests, 1 suite-level
error, 1 error, 1 failure, 34 ignored (18 assumptions) [seed: 78DC986344E9E28]
Total time: 177 minutes 57 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
