Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/80/
5 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not
released!!! [HdfsTransactionLog]
at __randomizedtesting.SeedInfo.seed([460DDE939476F360]: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:256)
at sun.reflect.GeneratedMethodAccessor103.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$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.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=56451, name=collection1,
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=56451, name=collection1, state=RUNNABLE,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:51849: Could not find collection :
awholynewstresscollection_collection1_1
at __randomizedtesting.SeedInfo.seed([460DDE939476F360]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:994)
FAILED:
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 12 object(s) that were not released!!!
[NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory,
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory,
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory,
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 12 object(s) that were not
released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory,
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory,
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory,
NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
at __randomizedtesting.SeedInfo.seed([460DDE939476F360]: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:256)
at sun.reflect.GeneratedMethodAccessor20.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$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: junit.framework.TestSuite.org.apache.solr.schema.TestManagedSchemaAPI
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not
released!!! [MockDirectoryWrapper]
at __randomizedtesting.SeedInfo.seed([460DDE939476F360]: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:256)
at sun.reflect.GeneratedMethodAccessor20.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$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.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=680, name=collection5,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=680, 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:60277/f_gl/nu: collection already exists:
awholynewstresscollection_collection5_1
at __randomizedtesting.SeedInfo.seed([460DDE939476F360]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)
Build Log:
[...truncated 11236 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/init-core-data-001
[junit4] 2> 32456 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[460DDE939476F360]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
[junit4] 2> 32457 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[460DDE939476F360]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
/f_gl/nu
[junit4] 2> 32484 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 32529 INFO (Thread-178) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 32531 INFO (Thread-178) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 32721 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.ZkTestServer start zk server on port:38554
[junit4] 2> 32737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 33111 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 33156 INFO (zkCallback-15-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@1e8eb418
name:ZooKeeperConnection Watcher:127.0.0.1:38554 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 33157 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 33157 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 33158 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 33175 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 33181 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 0x1550bfa8d870000, 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> 33188 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 33227 INFO (zkCallback-16-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@1360f004
name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 33228 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 33229 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 33230 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 33236 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 33238 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 33242 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 33244 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 33245 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 33249 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 33249 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 33251 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 33252 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 33260 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 33260 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 33262 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 33262 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 33264 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 33264 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 33268 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 33268 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 33269 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 33269 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 33271 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 33271 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 33278 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 33278 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 33279 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 33279 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 33469 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1
[junit4] 2> 33477 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 33585 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@74aea88e{/f_gl/nu,null,AVAILABLE}
[junit4] 2> 33586 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@7a1c2954{HTTP/1.1,[http/1.1]}{127.0.0.1:47308}
[junit4] 2> 33586 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.e.j.s.Server Started @37520ms
[junit4] 2> 33586 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/tempDir-001/control/data,
hostContext=/f_gl/nu, hostPort=47308,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores}
[junit4] 2> 33588 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 33596 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001'
[junit4] 2> 33597 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 33597 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 33597 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 33615 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 33656 INFO (zkCallback-17-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3316ba31
name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 33657 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 33657 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 33668 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 33669 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/solr.xml
[junit4] 2> 33677 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores
[junit4] 2> 33677 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1345304922
[junit4] 2> 33677 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001]
[junit4] 2> 33677 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/lib
[junit4] 2> 33677 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
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> 33684 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 33685 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 33685 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 33685 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38554/solr
[junit4] 2> 33695 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 33695 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 33723 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 33724 INFO (zkCallback-20-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5779ce10
name:ZooKeeperConnection Watcher:127.0.0.1:38554 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 33724 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 33725 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 33728 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.ZkController Added new OnReconnect
listener org.apache.solr.cloud.ZkController$$Lambda$49/1942015045@e81bb13
[junit4] 2> 33768 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ConnectionManager Waiting for
client to connect to ZooKeeper
[junit4] 2> 33805 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@1a8dfc5 name:ZooKeeperConnection
Watcher:127.0.0.1:38554/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 33814 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ConnectionManager Client is
connected to ZooKeeper
[junit4] 2> 33935 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer/queue
[junit4] 2> 33940 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 33952 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 33957 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 33959 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 33970 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 33972 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 33973 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/clusterstate.json
[junit4] 2> 33975 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/security.json
[junit4] 2> 33979 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader Updating cluster
state from ZooKeeper...
[junit4] 2> 33987 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader Loaded empty cluster
properties
[junit4] 2> 33991 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (0)
[junit4] 2> 34005 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect
[junit4] 2> 34006 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 34008 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 34011 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.LeaderElector Joined leadership
election with path:
/overseer_elect/election/95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000
[junit4] 2> 34012 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.OverseerElectionContext I am going to
be the leader 127.0.0.1:47308_f_gl%2Fnu
[junit4] 2> 34012 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/leader
[junit4] 2> 34014 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.Overseer Overseer
(id=95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000) starting
[junit4] 2> 34018 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/overseer/queue-work
[junit4] 2> 34059 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.OverseerAutoReplicaFailoverThread
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 34069 INFO
(OverseerCollectionConfigSetProcessor-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.OverseerTaskProcessor Process
current queue of overseer operations
[junit4] 2> 34069 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.Overseer Starting to work on the main
queue
[junit4] 2> 34076 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47308_f_gl%2Fnu
[junit4] 2> 34085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:47308_f_gl%2Fnu
[junit4] 2> 34086 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (0)
[junit4] 2> 34091 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader A live node change:
[WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes],
has occurred - updating... (live nodes size: [0])
[junit4] 2> 34092 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 34281 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.CoreContainer Security conf doesn't
exist. Skipping setup for authorization module.
[junit4] 2> 34281 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.CoreContainer No authentication
plugin used.
[junit4] 2> 34283 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.CorePropertiesLocator Looking for
core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores
[junit4] 2> 34284 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.CoreDescriptor Created
CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json,
collection=control_collection, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 34284 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.CorePropertiesLocator Found core
collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1
[junit4] 2> 34284 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions
[junit4] 2> 34460 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1
[junit4] 2> 34460 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.s.SolrDispatchFilter
SolrDispatchFilter.init() done
[junit4] 2> 34460 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.ZkController publishing state=down
[junit4] 2> 34461 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.ZkController numShards not found on descriptor - reading it from system
property
[junit4] 2> 34464 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.Overseer processMessage: queueSize:
1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:47308/f_gl/nu";,
[junit4] 2> "node_name":"127.0.0.1:47308_f_gl%2Fnu",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 34469 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 34472 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
[junit4] 2> 34475 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.o.ReplicaMutator Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:47308/f_gl/nu";,
[junit4] 2> "node_name":"127.0.0.1:47308_f_gl%2Fnu",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 34477 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.o.ClusterStateMutator building a new
cName: control_collection
[junit4] 2> 34477 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.ZkController look for our core node name
[junit4] 2> 34494 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.o.ReplicaMutator Assigning new node
to shard shard=shard1
[junit4] 2> 34515 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu ] 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> 35530 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 35530 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 35532 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 35532 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 35533 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 35534 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1'
[junit4] 2> 35534 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 35534 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 35540 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 35563 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 35579 WARN
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 35580 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 35648 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 35681 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 36071 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 36079 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 36079 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 36111 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 36122 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 36126 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 36127 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 36128 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/configs/conf1',
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1
[junit4] 2> 36128 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 36129 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 36129 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/]
[junit4] 2> 36129 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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@7f39382
[junit4] 2> 36135 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data
[junit4] 2> 36136 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/index/
[junit4] 2> 36136 WARN
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 36136 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/index
[junit4] 2> 36136 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=1.0]
[junit4] 2> 36149 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60d25427
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@257235e3),segFN=segments_1,generation=1}
[junit4] 2> 36150 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 36189 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 36200 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 36205 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup
for core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/
[junit4] 2> 36205 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.DirectoryFactory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 36249 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 36249 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 36249 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 36249 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36249 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36250 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36250 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36250 WARN
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36251 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 36254 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 36264 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 36266 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 36314 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 36315 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36437 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36437 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36437 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 36437 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 36439 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@46d7b36f
[junit4] 2> 36444 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@60d25427
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@257235e3),segFN=segments_1,generation=1}
[junit4] 2> 36444 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 36445 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@7142ea7e[collection1] main]
[junit4] 2> 36445 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36446 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36446 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36447 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 36447 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 36447 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36447 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36448 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36448 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 36448 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 36450 INFO
(searcherExecutor-74-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@7142ea7e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 36450 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36450 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36450 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36450 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu 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 1535938208772128768
[junit4] 2> 36451 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with
highest version 1535938208772128768
[junit4] 2> 36452 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 36457 INFO
(coreLoadExecutor-73-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 36464 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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:47308/f_gl/nu collection:control_collection
shard:shard1
[junit4] 2> 36473 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36476 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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/95996137802498052-core_node1-n_0000000000
[junit4] 2> 36476 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36478 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 36478 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36479 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:47308/f_gl/nu/collection1/
[junit4] 2> 36479 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 36479 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:47308/f_gl/nu/collection1/
has no replicas
[junit4] 2> 36481 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] 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> 36487 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.c.SolrZkClient makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 36488 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu ] 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> 36502 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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/95996137802498052-core_node1-n_0000000000
[junit4] 2> 36518 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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:47308/f_gl/nu/collection1/ shard1
[junit4] 2> 36519 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] 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:47308/f_gl/nu";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 36537 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string:
127.0.0.1:38554/solr
[junit4] 2> 36539 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 36568 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 36601 INFO (zkCallback-25-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@68a30b70
name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 36601 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 36601 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 36601 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 36602 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 36603 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 36605 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 36605 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 36605 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 36615 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 36621 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu ] 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> 36627 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController We are
http://127.0.0.1:47308/f_gl/nu/collection1/ and leader is
http://127.0.0.1:47308/f_gl/nu/collection1/
[junit4] 2> 36627 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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:47308/f_gl/nu
[junit4] 2> 36627 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 36627 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 36627 INFO
(coreZkRegister-67-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:47308_f_gl%2Fnu 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> 36638 INFO (zkCallback-25-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [1])
[junit4] 2> 36641 INFO (zkCallback-26-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@299b7a7b
name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 36641 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 36642 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 36642 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.Overseer processMessage: queueSize:
1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:47308/f_gl/nu";,
[junit4] 2> "node_name":"127.0.0.1:47308_f_gl%2Fnu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 36643 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.o.ReplicaMutator Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:47308/f_gl/nu";,
[junit4] 2> "node_name":"127.0.0.1:47308_f_gl%2Fnu",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 36647 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.Overseer processMessage: queueSize:
1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 3
[junit4] 2> 36647 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.o.ClusterStateMutator building a new
cName: collection1
[junit4] 2> 36647 INFO (zkCallback-25-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [1])
[junit4] 2> 36647 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu ] 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> 36758 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.o.ZkStateWriter going to
create_collection /collections/collection1/state.json
[junit4] 2> 37000 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001/cores/collection1
[junit4] 2> 37018 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001
[junit4] 2> 37020 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 37072 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@123a0d40{/f_gl/nu,null,AVAILABLE}
[junit4] 2> 37085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@dbc8fbe{HTTP/1.1,[http/1.1]}{127.0.0.1:46014}
[junit4] 2> 37085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.e.j.s.Server Started @41019ms
[junit4] 2> 37085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/f_gl/nu, hostPort=46014,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001/cores}
[junit4] 2> 37085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 37085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001'
[junit4] 2> 37085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 37085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 37085 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37093 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37116 INFO (zkCallback-27-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7012ca9f
name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37117 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37117 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37121 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 37121 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001/solr.xml
[junit4] 2> 37125 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001/cores
[junit4] 2> 37126 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.CoreContainer New CoreContainer 962351997
[junit4] 2> 37126 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001]
[junit4] 2> 37126 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_460DDE939476F360-001/shard-1-001/lib
[junit4] 2> 37126 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
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> 37127 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 37127 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 37127 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 37127 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38554/solr
[junit4] 2> 37127 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 37127 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37148 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37161 INFO (zkCallback-30-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3e617cd4
name:ZooKeeperConnection Watcher:127.0.0.1:38554 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37172 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37173 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37175 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.ZkController Added new OnReconnect
listener org.apache.solr.cloud.ZkController$$Lambda$49/1942015045@659dd8d5
[junit4] 2> 37177 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.c.ConnectionManager Waiting for
client to connect to ZooKeeper
[junit4] 2> 37196 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:46014_f_gl%2Fnu)
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@637a59ca
name:ZooKeeperConnection Watcher:127.0.0.1:38554/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37196 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.c.ConnectionManager Client is
connected to ZooKeeper
[junit4] 2> 37210 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader Updating cluster
state from ZooKeeper...
[junit4] 2> 37211 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader Loaded empty cluster
properties
[junit4] 2> 37211 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 37213 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 37214 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.LeaderElector Joined leadership
election with path:
/overseer_elect/election/95996137802498057-127.0.0.1:46014_f_gl%2Fnu-n_0000000001
[junit4] 2> 37215 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000
to know if I could be the leader
[junit4] 2> 37215 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[460DDE939476F360])
[n:127.0.0.1:46014_f_gl%2Fnu ] o.a.s.c.ZkController Publish
node=127.0.0.1:46014_f_gl%2Fnu as DOWN
[junit4] 2> 37217 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.Overseer processMessage: queueSize:
1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:46014_f_gl%2Fnu"} current state
version: 4
[junit4] 2> 37218 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.o.NodeMutator DownNode state invoked
for node: 127.0.0.1:46014_f_gl%2Fnu
[junit4] 2> 37219 INFO (zkCallback-25-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [1])
[junit4] 2> 37219 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47308_f_gl%2Fnu)
[n:127.0.0.1:47308_f_gl%2Fnu ] 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> 37221 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:46014_f_gl%2Fnu)
[n:127.0.0.1:46014_f_gl%2Fnu ] 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> 37324 INFO
(OverseerStateUpdate-95996137802498052-127.0.0.1:47308_f_gl%2Fnu-n_0000000000)
[n:127.0.0.1:47308_f_gl%2Fnu ] o.a.s.c.o.
[...truncated too long message...]
uteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 4905167 WARN (Thread-13311) [n:127.0.0.1:54891_solr
c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2]
o.a.s.c.ZkController listener throws error
[junit4] 2> org.apache.solr.common.SolrException: Unable to reload core
[testschemaapi_shard1_replica2]
[junit4] 2> at
org.apache.solr.core.CoreContainer.reload(CoreContainer.java:920)
[junit4] 2> at
org.apache.solr.core.SolrCore.lambda$getConfListener$6(SolrCore.java:2510)
[junit4] 2> at
org.apache.solr.cloud.ZkController$4.run(ZkController.java:2408)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Failed to
verify znode at /configs/conf1 due to:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /configs/conf1
[junit4] 2> at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:773)
[junit4] 2> at
org.apache.solr.core.SolrCore.reload(SolrCore.java:462)
[junit4] 2> at
org.apache.solr.core.CoreContainer.reload(CoreContainer.java:914)
[junit4] 2> ... 2 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Failed to
verify znode at /configs/conf1 due to:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /configs/conf1
[junit4] 2> at
org.apache.solr.rest.ManagedResourceStorage$ZooKeeperStorageIO.configure(ManagedResourceStorage.java:236)
[junit4] 2> at
org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:141)
[junit4] 2> at
org.apache.solr.core.SolrCore.initRestManager(SolrCore.java:2305)
[junit4] 2> at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:749)
[junit4] 2> ... 4 more
[junit4] 2> Caused by:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /configs/conf1
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:314)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:311)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:311)
[junit4] 2> at
org.apache.solr.rest.ManagedResourceStorage$ZooKeeperStorageIO.configure(ManagedResourceStorage.java:229)
[junit4] 2> ... 7 more
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60):
{_version_=PostingsFormat(name=MockRandom), id=Lucene50(blocksize=128),
myNewField1=Lucene50(blocksize=128), myNewField=Lucene50(blocksize=128)},
docValues:{}, maxPointsInLeafNode=88, maxMBSortInHeap=7.964266233061676,
sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=pl-PL,
timezone=America/Curacao
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_74 (64-bit)/cpus=4,threads=1,free=133589376,total=525336576
[junit4] 2> NOTE: All tests run in this JVM:
[DistributedFacetPivotLongTailTest, CircularListTest,
TestSuggestSpellingConverter, LeaderElectionTest, TestDefaultStatsCache,
TestFuzzyAnalyzedSuggestions, UpdateParamsTest, TestIndexSearcher,
TestCollationField, SuggesterTest, TestSearchPerf, TestImplicitCoreProperties,
SimpleCollectionCreateDeleteTest, CleanupOldIndexTest, ShardSplitTest,
TestExactStatsCache, BinaryUpdateRequestHandlerTest,
TestOverriddenPrefixQueryForCustomFieldType, TestRuleBasedAuthorizationPlugin,
TestStressLiveNodes, TestManagedSchema, TestSQLHandler,
TestNamedUpdateProcessors, FieldMutatingUpdateProcessorTest,
AnalysisErrorHandlingTest, TestSchemaVersionResource,
FieldAnalysisRequestHandlerTest, RequestLoggingTest, TestCryptoKeys,
TestSolrConfigHandler, HdfsBasicDistributedZk2Test, RemoteQueryErrorTest,
TestWriterPerf, TestStressRecovery, TestManagedSynonymFilterFactory, TestSolrJ,
TestTolerantSearch, TestFileDictionaryLookup, TestCoreDiscovery,
JavabinLoaderTest, RollingRestartTest, InfoHandlerTest, TestStandardQParsers,
DistanceUnitsTest, TestZkChroot, TestMaxScoreQueryParser, QueryResultKeyTest,
OverriddenZkACLAndCredentialsProvidersTest, TestSolrCLIRunExample,
TestPerFieldSimilarityClassic, TestGroupingSearch, TestSortingResponseWriter,
DateMathParserTest, TestReversedWildcardFilterFactory,
BigEndianAscendingWordSerializerTest, SliceStateTest,
TestGraphTermsQParserPlugin, CoreAdminCreateDiscoverTest, BadComponentTest,
TestSolrQueryParser, SpatialRPTFieldTypeTest, CollectionStateFormat2Test,
TestSchemaNameResource, ClassificationUpdateProcessorFactoryTest,
OverseerStatusTest, TestNRTOpen, RequestHandlersTest, AnalyticsQueryTest,
SmileWriterTest, BlockJoinFacetDistribTest, SampleTest, TestJettySolrRunner,
SearchHandlerTest, RankQueryTest, CollectionsAPISolrJTest, SparseHLLTest,
BadIndexSchemaTest, HdfsRecoverLeaseTest, TestCSVResponseWriter,
CoreAdminHandlerTest, TestSweetSpotSimilarityFactory, BasicDistributedZk2Test,
HdfsUnloadDistributedZkTest, TestSolrDeletionPolicy1, DocValuesMultiTest,
VersionInfoTest, LeaderFailoverAfterPartitionTest, CurrencyFieldXmlFileTest,
TestFieldCacheSort, BaseCdcrDistributedZkTest, TestFieldCache,
StatelessScriptUpdateProcessorFactoryTest, TestXIncludeConfig, TestXmlQParser,
DebugComponentTest, DistributedFacetPivotWhiteBoxTest,
TestPHPSerializedResponseWriter,
IgnoreCommitOptimizeUpdateProcessorFactoryTest, HdfsSyncSliceTest,
DirectoryFactoryTest, OverseerTest, LeaderElectionIntegrationTest,
ShardRoutingTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest,
TestReplicationHandler, CdcrVersionReplicationTest,
CollectionTooManyReplicasTest, ConnectionManagerTest, CustomCollectionTest,
DistributedQueueTest, DistributedVersionInfoTest, HttpPartitionTest,
SSLMigrationTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverTest,
TestCloudBackupRestore, TestCloudDeleteByQuery, TestCloudInspectUtil,
TestCloudPivotFacet, TestConfigSetsAPI, TestConfigSetsAPIExclusivity,
TestDistribDocBasedVersion, TestMiniSolrCloudClusterBase,
TestRandomRequestDistribution, TestRequestForwarding,
TestRequestStatusCollectionAPI, TestSizeLimitedDistributedMap,
TestStressCloudBlindAtomicUpdates, HdfsRecoveryZkTest, HdfsThreadLeakTest,
HdfsWriteToMultipleCollectionsTest, BlobRepositoryCloudTest,
ExitableDirectoryReaderTest, TestConfigSetProperties, TestConfigSets,
TestInitParams, TestSolrDynamicMBean, TestSolrXml,
TestReplicationHandlerBackup, CoreMergeIndexesAdminHandlerTest,
DistributedDebugComponentTest, DistributedFacetPivotLargeTest,
DistributedFacetPivotSmallAdvancedTest,
DistributedQueryComponentCustomSortTest,
DistributedQueryComponentOptimizationTest, DistributedSuggestComponentTest,
SpatialHeatmapFacetsTest, HighlighterMaxOffsetTest,
TestSlowCompositeReaderWrapper, WrapperMergePolicyFactoryTest,
TestIntervalFaceting, TestCustomDocTransformer, TestGeoJSONResponseWriter,
TestGraphMLResponseWriter, TestRawResponseWriter, TestChildDocTransformer,
TestSubQueryTransformer, TestSubQueryTransformerCrossCore,
TestSubQueryTransformerDistrib, TestManagedSchemaAPI]
[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=TestManagedSchemaAPI -Dtests.seed=460DDE939476F360
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=pl-PL -Dtests.timezone=America/Curacao -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | TestManagedSchemaAPI (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1
object(s) that were not released!!! [MockDirectoryWrapper]
[junit4] > at
__randomizedtesting.SeedInfo.seed([460DDE939476F360]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [549/612 (5!)] on J2 in 14.84s, 1 test, 1 failure <<<
FAILURES!
[...truncated 200 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:531:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1427:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984:
There were test failures: 612 suites (10 ignored), 2564 tests, 3 suite-level
errors, 2 errors, 33 ignored (18 assumptions) [seed: 460DDE939476F360]
Total time: 252 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
