Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/14/
4 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
KeeperErrorCode = Session expired for /clusterstate.json
Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /clusterstate.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:345)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:342)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:342)
at
org.apache.solr.common.cloud.ZkStateReader.refreshLegacyClusterState(ZkStateReader.java:433)
at
org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:238)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForCollectionToDisappear(AbstractDistribZkTestBase.java:198)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.assertCollectionNotExists(AbstractFullDistribZkTestBase.java:1773)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection(CollectionsAPIDistributedZkTest.java:241)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:181)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:52960
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting
response from server at: http://127.0.0.1:52960
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:586)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:400)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:417)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:179)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:152)
at java.net.SocketInputStream.read(SocketInputStream.java:122)
at
org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at
org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at
org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
at
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
at
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482)
... 47 more
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest: 1)
Thread[id=7349, name=Thread-4775, state=TIMED_WAITING,
group=TGRP-HdfsCollectionsAPIDistributedZkTest] at
java.lang.Object.wait(Native Method) at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest:
1) Thread[id=7349, name=Thread-4775, state=TIMED_WAITING,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
at java.lang.Object.wait(Native Method)
at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
at __randomizedtesting.SeedInfo.seed([8BB34BE87E91D123]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=7349, name=Thread-4775, state=TIMED_WAITING,
group=TGRP-HdfsCollectionsAPIDistributedZkTest] at
java.lang.Object.wait(Native Method) at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=7349, name=Thread-4775, state=TIMED_WAITING,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
at java.lang.Object.wait(Native Method)
at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
at __randomizedtesting.SeedInfo.seed([8BB34BE87E91D123]:0)
Build Log:
[...truncated 11759 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/init-core-data-001
[junit4] 2> 1549644 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[8BB34BE87E91D123]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
[junit4] 2> 1549644 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[8BB34BE87E91D123]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1549650 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1549651 INFO (Thread-10489) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1549651 INFO (Thread-10489) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1549751 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.ZkTestServer start zk server on port:39046
[junit4] 2> 1549751 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1549752 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1549754 INFO (zkCallback-948-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3e3711a5
name:ZooKeeperConnection Watcher:127.0.0.1:39046 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1549754 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1549754 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1549755 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1549757 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1549758 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1549761 INFO (zkCallback-949-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@10785c8e
name:ZooKeeperConnection Watcher:127.0.0.1:39046/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1549761 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1549761 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1549761 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 1549762 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 1549763 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 1549764 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 1549764 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1549764 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1549765 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 1549766 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 1549767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1549767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1549767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1549767 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1549768 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1549768 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 1549769 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1549769 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 1549770 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1549770 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1549770 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1549771 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1549771 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1549771 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1549772 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1549772 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1549773 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1549773 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1549774 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1549774 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1549775 INFO (zkCallback-950-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@719a4023
name:ZooKeeperConnection Watcher:127.0.0.1:39046/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1549775 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1549775 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1549836 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1
[junit4] 2> 1549838 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1549841 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@229f99d3{/,null,AVAILABLE}
[junit4] 2> 1549854 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@3bd72e9a{SSL-http/1.1}{127.0.0.1:42419}
[junit4] 2> 1549854 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.e.j.s.Server Started @1552150ms
[junit4] 2> 1549854 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores,
hostPort=42419,
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/tempDir-001/control/data,
hostContext=/}
[junit4] 2> 1549855 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@3827de24
[junit4] 2> 1549855 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001'
[junit4] 2> 1549855 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1549855 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1549855 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1549861 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1549861 INFO (zkCallback-951-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3ea005d3
name:ZooKeeperConnection Watcher:127.0.0.1:39046/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1549861 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1549862 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1549867 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1549867 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/solr.xml
[junit4] 2> 1549877 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores
[junit4] 2> 1549877 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.CoreContainer New CoreContainer 624602778
[junit4] 2> 1549877 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001]
[junit4] 2> 1549877 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/lib
[junit4] 2> 1549878 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
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> 1549880 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1549881 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1549881 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1549881 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39046/solr
[junit4] 2> 1549881 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1549881 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1549882 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1549892 INFO (zkCallback-954-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3610a759
name:ZooKeeperConnection Watcher:127.0.0.1:39046 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1549892 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1549892 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1549894 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.ZkController Added new OnReconnect listener
org.apache.solr.cloud.ZkController$6@3b4ca51e
[junit4] 2> 1549898 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1549939 INFO
(zkCallback-955-thread-1-processing-n:127.0.0.1:42419_) [n:127.0.0.1:42419_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7c7aee4c
name:ZooKeeperConnection Watcher:127.0.0.1:39046/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1549940 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 1549941 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1549942 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 1549943 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 1549944 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 1549945 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 1549946 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1549954 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1549955 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1549955 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1549956 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42419_
[junit4] 2> 1549956 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:42419_
[junit4] 2> 1549962 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1549963 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 1549963 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1549964 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/96090956425265157-127.0.0.1:42419_-n_0000000000
[junit4] 2> 1549964 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:42419_
[junit4] 2> 1549964 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1549970 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.Overseer Overseer
(id=96090956425265157-127.0.0.1:42419_-n_0000000000) starting
[junit4] 2> 1549971 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1549974 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1549986 INFO
(OverseerCollectionConfigSetProcessor-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] o.a.s.c.OverseerTaskProcessor Process current queue
of overseer operations
[junit4] 2> 1549990 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 1549990 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1550011 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 1550011 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1550011 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores
[junit4] 2> 1550012 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{collection=control_collection, shard=, transient=false, loadOnStartup=true,
schema=schema.xml, name=collection1, configSetProperties=configsetprops.json,
coreNodeName=, config=solrconfig.xml, dataDir=data/}
[junit4] 2> 1550012 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.CorePropertiesLocator Found core collection1
in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1
[junit4] 2> 1550012 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1550022 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 1550022 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 1550036 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
[junit4] 2> 1550036 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:42419_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 1550036 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1550036 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader addZkWatch [control_collection]
[junit4] 2> 1550036 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:42419";,
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"1",
[junit4] 2> "node_name":"127.0.0.1:42419_",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "core":"collection1"} current state version: 0
[junit4] 2> 1550037 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
[junit4] 2> 1550037 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:42419";,
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"1",
[junit4] 2> "node_name":"127.0.0.1:42419_",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 1550037 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
control_collection
[junit4] 2> 1550037 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 1550037 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 1550050 INFO
(zkCallback-955-thread-1-processing-n:127.0.0.1:42419_) [n:127.0.0.1:42419_
] 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> 1551054 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 1551054 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.ZkController
Check for collection zkNode:control_collection
[junit4] 2> 1551054 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 1551054 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 1551054 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 1551055 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1'
[junit4] 2> 1551055 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1551055 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1551056 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 1551060 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 1551076 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 5.5.2
[junit4] 2> 1551106 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1551111 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1551274 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1551280 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.s.IndexSchema
default search field in schema is text
[junit4] 2> 1551285 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.s.IndexSchema
unique key field: id
[junit4] 2> 1551295 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1551296 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1551298 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 1551298 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 1551299 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/configs/conf1',
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
[junit4] 2> 1551299 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 1551299 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1551300 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1/data/]
[junit4] 2> 1551300 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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@6225747a
[junit4] 2> 1551300 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1/data
[junit4] 2> 1551300 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1/data/index/
[junit4] 2> 1551300 WARN
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1551300 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1/data/index
[junit4] 2> 1551301 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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@352a56c7
[junit4] 2> 1551301 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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@6ea73e15
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@25dd0d74),segFN=segments_1,generation=1}
[junit4] 2> 1551301 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1551302 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
Looking for old index directories to cleanup for core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1/data/
[junit4] 2> 1551304 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 1551304 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 1551304 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1551304 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 1551304 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1551304 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551305 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551305 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551305 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551305 WARN
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551308 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1551309 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1551310 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1551311 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1551313 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,/admin/ping,/schema,/update/json,/update,/admin/segments,/config,standard,/admin/threads,/replication,/admin/logging,/get,/dump,/admin/luke,/update/csv,/admin/plugins,/admin/system,/update/json/docs,/admin/file,/admin/properties
[junit4] 2> 1551314 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551314 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551314 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551315 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1551315 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1551315 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.DirectoryFactory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 1551317 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=349698352,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1551318 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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@6ea73e15
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@25dd0d74),segFN=segments_1,generation=1}
[junit4] 2> 1551318 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1551319 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@228f06f3[collection1] main
[junit4] 2> 1551319 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551319 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551319 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551319 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1551319 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1551319 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551320 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551320 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551320 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1551320 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1551320 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551320 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551320 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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> 1551320 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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 1537455304553267200
[junit4] 2> 1551322 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ 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
1537455304553267200
[junit4] 2> 1551322 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 1551323 INFO
(coreLoadExecutor-3321-thread-1-processing-n:127.0.0.1:42419_)
[n:127.0.0.1:42419_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1551323 INFO
(searcherExecutor-3322-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@228f06f3[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1551324 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
Register replica - core:collection1 address:https://127.0.0.1:42419
collection:control_collection shard:shard1
[junit4] 2> 1551324 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
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> 1551326 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
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/96090956425265157-core_node1-n_0000000000
[junit4] 2> 1551327 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
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> 1551328 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] 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> 1551329 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1551329 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
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> 1551329 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to https://127.0.0.1:42419/collection1/
[junit4] 2> 1551329 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1551329 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
https://127.0.0.1:42419/collection1/ has no replicas
[junit4] 2> 1551329 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1551330 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
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/96090956425265157-core_node1-n_0000000000
[junit4] 2> 1551331 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:42419/collection1/ shard1
[junit4] 2> 1551332 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] 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":"https://127.0.0.1:42419";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1551434 INFO
(zkCallback-955-thread-4-processing-n:127.0.0.1:42419_) [n:127.0.0.1:42419_
] 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> 1551483 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are https://127.0.0.1:42419/collection1/ and leader is
https://127.0.0.1:42419/collection1/
[junit4] 2> 1551483 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:42419
[junit4] 2> 1551483 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1551483 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 1551484 INFO
(coreZkRegister-3315-thread-1-processing-c:control_collection r:core_node1
n:127.0.0.1:42419_ s:shard1 x:collection1) [n:127.0.0.1:42419_
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> 1551485 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:42419";,
[junit4] 2> "state":"active",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:42419_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "core":"collection1"} current state version: 2
[junit4] 2> 1551485 INFO
(OverseerStateUpdate-96090956425265157-127.0.0.1:42419_-n_0000000000)
[n:127.0.0.1:42419_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:42419";,
[junit4] 2> "state":"active",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:42419_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 1551540 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1551572 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1551578 INFO (zkCallback-957-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6be5ff5a
name:ZooKeeperConnection Watcher:127.0.0.1:39046/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1551578 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1551578 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1551578 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1551579 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1551587 INFO
(zkCallback-955-thread-5-processing-n:127.0.0.1:42419_) [n:127.0.0.1:42419_
] 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> 1551593 INFO (zkCallback-957-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> 1551642 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001/cores/collection1
[junit4] 2> 1551643 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001
[junit4] 2> 1551646 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1551733 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7546d81a{/,null,AVAILABLE}
[junit4] 2> 1551737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@49d077ef{SSL-http/1.1}{127.0.0.1:60750}
[junit4] 2> 1551737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.e.j.s.Server Started @1554033ms
[junit4] 2> 1551737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001/cores,
hostContext=/, hostPort=60750, solrconfig=solrconfig.xml,
solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/tempDir-001/jetty1}
[junit4] 2> 1551737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@3827de24
[junit4] 2> 1551737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001'
[junit4] 2> 1551737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1551737 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1551738 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1551758 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1551774 INFO (zkCallback-958-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5804a06f
name:ZooKeeperConnection Watcher:127.0.0.1:39046/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1551774 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1551774 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1551786 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1551786 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001/solr.xml
[junit4] 2> 1551795 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001/cores
[junit4] 2> 1551795 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1352604044
[junit4] 2> 1551795 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001]
[junit4] 2> 1551795 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001/lib
[junit4] 2> 1551795 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
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> 1551797 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1551798 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1551798 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1551798 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39046/solr
[junit4] 2> 1551798 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1551798 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1551823 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1551838 INFO (zkCallback-961-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6388de38
name:ZooKeeperConnection Watcher:127.0.0.1:39046 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1551838 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1551838 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1551839 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.ZkController Added new OnReconnect listener
org.apache.solr.cloud.ZkController$6@3a1de2e1
[junit4] 2> 1551840 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 1551840 INFO
(zkCallback-962-thread-1-processing-n:127.0.0.1:60750_) [n:127.0.0.1:60750_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@671400dd
name:ZooKeeperConnection Watcher:127.0.0.1:39046/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1551840 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 1551843 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 1552846 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60750_
[junit4] 2> 1552847 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:60750_
[junit4] 2> 1552848 INFO (zkCallback-957-thread-1) [ ]
o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [1])
[junit4] 2> 1552848 INFO
(zkCallback-962-thread-1-processing-n:127.0.0.1:60750_) [n:127.0.0.1:60750_
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [1])
[junit4] 2> 1552848 INFO
(zkCallback-955-thread-5-processing-n:127.0.0.1:42419_) [n:127.0.0.1:42419_
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [1])
[junit4] 2> 1552849 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1552850 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/96090956425265161-127.0.0.1:60750_-n_0000000001
[junit4] 2> 1552850 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/96090956425265157-127.0.0.1:42419_-n_0000000000 to
know if I could be the leader
[junit4] 2> 1552859 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 1552859 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1552860 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001/cores
[junit4] 2> 1552860 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{collection=collection1, loadOnStartup=true, shard=, coreNodeName=,
configSetProperties=configsetprops.json, name=collection1, transient=false,
config=solrconfig.xml, dataDir=data/, schema=schema.xml}
[junit4] 2> 1552860 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.CorePropertiesLocator Found core collection1
in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8BB34BE87E91D123-001/shard-1-001/cores/collection1
[junit4] 2> 1552860 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1552861 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1
[junit4] 2> 1552861 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[8BB34BE87E91D123])
[n:127.0.0.1:60750_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 1552862 INFO
(coreLoadExecutor-3332-thread-1-processing-n:127.0.0.1:60750_)
[n:127.0.0.1:60750_ c:collection1 x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 1552862 INFO
(coreLoadExecutor-3332-thread-1-processing-n:127.0.0.1:60750_)
[n:127.0.0.1:60750_ c:collection1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 1552863 INFO
(coreLoadExecutor-3332-thread-1-processing-n:127.0.0.1:60750_)
[n:127.0.0.1:60750_ c:collection1 x:collection1] o.a.s.c.ZkController
Collection collection1 not visible yet, but flagging it so a watch is
registered when it becomes visible
[junit4] 2> 1552863 INFO (OverseerStateUpdate-
[...truncated too long message...]
rd6 r:core_node22 x:awholynewstresscollection_collection3_0_shard6_replica3]
o.a.h.h.DFSClient Caught exception
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
[junit4] 2> 2446340 WARN (LeaseRenewer:jenkins@localhost:52168) [ ]
o.a.h.h.LeaseRenewer Failed to renew lease for
[DFSClient_NONMAPREDUCE_1398239136_6359] for 52 seconds. Will retry shortly ...
[junit4] 2> java.net.ConnectException: Call From lucene1-us-west/10.41.0.5
to localhost:52168 failed on connection exception: java.net.ConnectException:
Connection refused; For more details see:
http://wiki.apache.org/hadoop/ConnectionRefused
[junit4] 2> at
sun.reflect.GeneratedConstructorAccessor306.newInstance(Unknown Source)
[junit4] 2> at
sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
[junit4] 2> at
java.lang.reflect.Constructor.newInstance(Constructor.java:526)
[junit4] 2> at
org.apache.hadoop.net.NetUtils.wrapWithMessage(NetUtils.java:791)
[junit4] 2> at
org.apache.hadoop.net.NetUtils.wrapException(NetUtils.java:731)
[junit4] 2> at org.apache.hadoop.ipc.Client.call(Client.java:1472)
[junit4] 2> at org.apache.hadoop.ipc.Client.call(Client.java:1399)
[junit4] 2> at
org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:232)
[junit4] 2> at com.sun.proxy.$Proxy48.renewLease(Unknown Source)
[junit4] 2> at
org.apache.hadoop.hdfs.protocolPB.ClientNamenodeProtocolTranslatorPB.renewLease(ClientNamenodeProtocolTranslatorPB.java:571)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown
Source)
[junit4] 2> at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:606)
[junit4] 2> at
org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:187)
[junit4] 2> at
org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102)
[junit4] 2> at com.sun.proxy.$Proxy49.renewLease(Unknown Source)
[junit4] 2> at
org.apache.hadoop.hdfs.DFSClient.renewLease(DFSClient.java:879)
[junit4] 2> at
org.apache.hadoop.hdfs.LeaseRenewer.renew(LeaseRenewer.java:417)
[junit4] 2> at
org.apache.hadoop.hdfs.LeaseRenewer.run(LeaseRenewer.java:442)
[junit4] 2> at
org.apache.hadoop.hdfs.LeaseRenewer.access$700(LeaseRenewer.java:71)
[junit4] 2> at
org.apache.hadoop.hdfs.LeaseRenewer$1.run(LeaseRenewer.java:298)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native
Method)
[junit4] 2> at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:744)
[junit4] 2> at
org.apache.hadoop.net.SocketIOWithTimeout.connect(SocketIOWithTimeout.java:206)
[junit4] 2> at
org.apache.hadoop.net.NetUtils.connect(NetUtils.java:530)
[junit4] 2> at
org.apache.hadoop.net.NetUtils.connect(NetUtils.java:494)
[junit4] 2> at
org.apache.hadoop.ipc.Client$Connection.setupConnection(Client.java:607)
[junit4] 2> at
org.apache.hadoop.ipc.Client$Connection.setupIOstreams(Client.java:705)
[junit4] 2> at
org.apache.hadoop.ipc.Client$Connection.access$2800(Client.java:368)
[junit4] 2> at
org.apache.hadoop.ipc.Client.getConnection(Client.java:1521)
[junit4] 2> at org.apache.hadoop.ipc.Client.call(Client.java:1438)
[junit4] 2> ... 16 more
[junit4] 2> 2446654 WARN (Thread-4775) [n:127.0.0.1:53785_
c:awholynewstresscollection_collection3_0 s:shard6 r:core_node22
x:awholynewstresscollection_collection3_0_shard6_replica3] o.a.h.h.DFSClient
Caught exception
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
[junit4] 2> Jun 18, 2016 6:48:52 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=7349, name=Thread-4775, state=TIMED_WAITING,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54):
{id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
a_i1=FST50, timestamp=FST50, range_facet_l=FST50,
range_facet_l_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
multiDefault=FST50, a_t=FST50, intDefault=PostingsFormat(name=Memory
doPackFST= true), _version_=PostingsFormat(name=Memory doPackFST= true),
other_tl1=PostingsFormat(name=Memory doPackFST= true), range_facet_i_dv=FST50,
text=Lucene50(blocksize=128)},
docValues:{timestamp=DocValuesFormat(name=Lucene54),
range_facet_l_dv=DocValuesFormat(name=Memory),
range_facet_i_dv=DocValuesFormat(name=Lucene54)},
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=ro-RO, timezone=PLT
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.7.0_80 (64-bit)/cpus=4,threads=3,free=119021744,total=477102080
[junit4] 2> NOTE: All tests run in this JVM:
[OverriddenZkACLAndCredentialsProvidersTest, DistributedFacetPivotWhiteBoxTest,
FileBasedSpellCheckerTest, HLLSerializationTest, TestRandomFaceting,
PeerSyncTest, LeaderElectionIntegrationTest,
SignatureUpdateProcessorFactoryTest, CachingDirectoryFactoryTest, TestRTGBase,
TestInitParams, TestOnReconnectListenerSupport, TestPseudoReturnFields,
CoreAdminCreateDiscoverTest, TestMaxScoreQueryParser, HdfsRecoveryZkTest,
TestSolrXml, CopyFieldTest, TestCloudSchemaless, DistributedDebugComponentTest,
HdfsLockFactoryTest, DeleteShardTest, JSONWriterTest, RemoteQueryErrorTest,
TestRandomDVFaceting, TestFieldCollectionResource,
HdfsCollectionsAPIDistributedZkTest]
[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=HdfsCollectionsAPIDistributedZkTest -Dtests.seed=8BB34BE87E91D123
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=ro-RO -Dtests.timezone=PLT -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | HdfsCollectionsAPIDistributedZkTest (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest:
[junit4] > 1) Thread[id=7349, name=Thread-4775, state=TIMED_WAITING,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
[junit4] > at
__randomizedtesting.SeedInfo.seed([8BB34BE87E91D123]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=7349, name=Thread-4775, state=TIMED_WAITING,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at
org.apache.hadoop.hdfs.DFSOutputStream$DataStreamer.run(DFSOutputStream.java:564)
[junit4] > at
__randomizedtesting.SeedInfo.seed([8BB34BE87E91D123]:0)
[junit4] Completed [339/573 (2!)] on J0 in 1937.35s, 1 test, 3 errors <<<
FAILURES!
[...truncated 695 lines...]
[junit4] JVM J0: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/temp/junit4-J0-20160618_060802_877.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/heapdumps/java_pid13772.hprof
...
[junit4] Heap dump file created [620124681 bytes in 5.427 secs]
[junit4] <<< JVM J0: EOF ----
[...truncated 15 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033:
There were test failures: 573 suites (7 ignored), 2319 tests, 2 suite-level
errors, 2 errors, 881 ignored (12 assumptions) [seed: 8BB34BE87E91D123]
Total time: 168 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
