Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/821/
4 tests failed.
FAILED:
org.apache.solr.cloud.SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection
Error Message:
Delete action failed!
Stack Trace:
java.lang.AssertionError: Delete action failed!
at
__randomizedtesting.SeedInfo.seed([B49F89C8FEBF92DB:A7FCBBA7CFD02B7D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.SolrCloudExampleTest.doTestDeleteAction(SolrCloudExampleTest.java:169)
at
org.apache.solr.cloud.SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection(SolrCloudExampleTest.java:145)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:873)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=3196, name=collection3,
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=3196, name=collection3, state=RUNNABLE,
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:58356: Could not find collection :
awholynewstresscollection_collection3_0
at __randomizedtesting.SeedInfo.seed([B49F89C8FEBF92DB]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895)
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=44001, name=collection0,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=44001, name=collection0, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: java.lang.RuntimeException:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available
to handle this request:[http://127.0.0.1:32967/tv_mx,
http://127.0.0.1:52574/tv_mx, http://127.0.0.1:35816/tv_mx,
http://127.0.0.1:42093/tv_mx, http://127.0.0.1:58837/tv_mx]
at __randomizedtesting.SeedInfo.seed([B49F89C8FEBF92DB]:0)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:898)
Caused by: org.apache.solr.client.solrj.SolrServerException: No live
SolrServers available to handle this request:[http://127.0.0.1:32967/tv_mx,
http://127.0.0.1:52574/tv_mx, http://127.0.0.1:35816/tv_mx,
http://127.0.0.1:42093/tv_mx, http://127.0.0.1:58837/tv_mx]
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured
while waiting response from server at: http://127.0.0.1:52574/tv_mx
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:587)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
... 7 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:170)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
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:480)
... 11 more
FAILED:
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR
Error Message:
Captured an uncaught exception in thread: Thread[id=51242,
name=coreZkRegister-5187-thread-1, state=RUNNABLE,
group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=51242, name=coreZkRegister-5187-thread-1,
state=RUNNABLE, group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest]
Caused by: java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([B49F89C8FEBF92DB]:0)
at
org.apache.solr.cloud.ZkController.updateLeaderInitiatedRecoveryState(ZkController.java:2126)
at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:433)
at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197)
at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157)
at
org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:346)
at
org.apache.solr.cloud.ZkController.joinElection(ZkController.java:1113)
at org.apache.solr.cloud.ZkController.register(ZkController.java:926)
at org.apache.solr.cloud.ZkController.register(ZkController.java:881)
at org.apache.solr.core.ZkContainer$2.run(ZkContainer.java:183)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9926 lines...]
[junit4] Suite:
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/init-core-data-001
[junit4] 2> 284955 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 284955 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 285002 WARN
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 285013 WARN
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 285016 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 285027 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_51370_hdfs____yo2ca/webapp
[junit4] 2> 285144 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log NO JSP Support for /, did not find
org.apache.jasper.servlet.JspServlet
[junit4] 2> 285456 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51370
[junit4] 2> 285594 WARN
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 285595 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 285611 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_60252_datanode____.ervdxg/webapp
[junit4] 2> 285728 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log NO JSP Support for /, did not find
org.apache.jasper.servlet.JspServlet
[junit4] 2> 285994 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:60252
[junit4] 2> 286050 WARN
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 286051 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 286085 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_35232_datanode____yndiae/webapp
[junit4] 2> 286214 INFO (IPC Server handler 3 on 42096) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-aef57e15-51c3-46a6-bf2c-96ba5749335a node DatanodeRegistration(127.0.0.1,
datanodeUuid=241c8848-7ba1-469b-aa60-5d7eeba9b606, infoPort=60252,
ipcPort=46117, storageInfo=lv=-56;cid=testClusterID;nsid=1340850404;c=0),
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
[junit4] 2> 286214 INFO (IPC Server handler 3 on 42096) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-23c65777-3ea2-41b2-8ec4-fa2ba211c787 node DatanodeRegistration(127.0.0.1,
datanodeUuid=241c8848-7ba1-469b-aa60-5d7eeba9b606, infoPort=60252,
ipcPort=46117, storageInfo=lv=-56;cid=testClusterID;nsid=1340850404;c=0),
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
[junit4] 2> 286227 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log NO JSP Support for /, did not find
org.apache.jasper.servlet.JspServlet
[junit4] 2> 286410 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B49F89C8FEBF92DB]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:35232
[junit4] 2> 286489 INFO (IPC Server handler 8 on 42096) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-97a4113a-3b2c-4bc2-92f2-31d96372015d node DatanodeRegistration(127.0.0.1,
datanodeUuid=751a18cc-629c-4787-9c2a-fa9152fb21cf, infoPort=35232,
ipcPort=42302, storageInfo=lv=-56;cid=testClusterID;nsid=1340850404;c=0),
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
[junit4] 2> 286490 INFO (IPC Server handler 8 on 42096) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-253de805-41bf-4423-869c-02a1c1d16a38 node DatanodeRegistration(127.0.0.1,
datanodeUuid=751a18cc-629c-4787-9c2a-fa9152fb21cf, infoPort=35232,
ipcPort=42302, storageInfo=lv=-56;cid=testClusterID;nsid=1340850404;c=0),
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
[junit4] 2> 286566 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 286566 INFO (Thread-1424) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 286566 INFO (Thread-1424) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 286666 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.ZkTestServer start zk server on port:40220
[junit4] 2> 286667 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 286667 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 286669 INFO (zkCallback-225-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@694e9548
name:ZooKeeperConnection Watcher:127.0.0.1:40220 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 286669 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 286670 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 286670 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 286673 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 286673 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 286675 INFO (zkCallback-226-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@23717684
name:ZooKeeperConnection Watcher:127.0.0.1:40220/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 286675 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 286675 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 286675 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 286677 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 286678 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 286679 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 286683 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 286683 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 286686 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 286686 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 286687 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 286688 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 286689 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 286689 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 286691 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 286691 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 286692 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 286693 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 286694 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 286694 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 286696 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 286696 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 286697 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 286697 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 286699 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 286699 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 286701 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 286701 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 287012 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores/collection1
[junit4] 2> 287013 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 287015 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@64e1042a{/,null,AVAILABLE}
[junit4] 2> 287016 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@20bf053{HTTP/1.1}{127.0.0.1:58356}
[junit4] 2> 287016 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.e.j.s.Server Started @290203ms
[junit4] 2> 287016 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:42096/hdfs__localhost_42096__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001_tempDir-002_control_data,
hostContext=/, hostPort=58356,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores}
[junit4] 2> 287016 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 287016 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/'
[junit4] 2> 287017 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 287017 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 287031 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 287032 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 287033 INFO (zkCallback-227-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7ee49501
name:ZooKeeperConnection Watcher:127.0.0.1:40220/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 287033 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 287033 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 287035 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 287035 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/solr.xml
[junit4] 2> 287040 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.CoresLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores
[junit4] 2> 287041 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.CoreContainer New CoreContainer 397060055
[junit4] 2> 287041 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/]
[junit4] 2> 287041 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.CoreContainer loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/lib
[junit4] 2> 287041 WARN
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to
classloader: lib (resolved as:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/lib).
[junit4] 2> 287048 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
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> 287049 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 287049 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 287049 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 287050 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40220/solr
[junit4] 2> 287050 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 287050 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 287050 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 287051 INFO (zkCallback-229-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@50def9c4
name:ZooKeeperConnection Watcher:127.0.0.1:40220 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 287052 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 287052 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 287058 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 287058 INFO
(zkCallback-230-thread-1-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5c945bb6
name:ZooKeeperConnection Watcher:127.0.0.1:40220/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 287058 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 287059 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 287061 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 287063 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 287065 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 287067 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 287069 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 287070 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 287071 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 287072 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 287073 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58356_
[junit4] 2> 287074 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:58356_
[junit4] 2> 287079 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 287080 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 287081 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 287081 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/94687538570199044-127.0.0.1:58356_-n_0000000000
[junit4] 2> 287082 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:58356_
[junit4] 2> 287082 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 287085 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.Overseer Overseer
(id=94687538570199044-127.0.0.1:58356_-n_0000000000) starting
[junit4] 2> 287087 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 287093 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 287094 INFO
(OverseerCollectionConfigSetProcessor-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.OverseerTaskProcessor Process current queue
of overseer operations
[junit4] 2> 287094 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 287094 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 287098 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 287113 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 287113 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 287114 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.CoresLocator Looking for core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores
[junit4] 2> 287114 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.SolrCore Created CoreDescriptor:
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores/collection1,
collection=control_collection,
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores/collection1/,
coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 287114 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.CoresLocator Found core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores/collection1/
[junit4] 2> 287114 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 287116 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 287116 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 287116 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
[junit4] 2> 287116 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:58356_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 287117 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 287118 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:58356";,
[junit4] 2> "node_name":"127.0.0.1:58356_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 287118 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:58356";,
[junit4] 2> "node_name":"127.0.0.1:58356_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 287119 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
control_collection
[junit4] 2> 287119 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 287121 INFO
(zkCallback-230-thread-1-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] 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> 287121 INFO
(zkCallback-230-thread-2-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] 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> 288118 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 288118 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.ZkController
Check for collection zkNode:control_collection
[junit4] 2> 288118 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 288118 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config
from:/collections/control_collection
[junit4] 2> 288119 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1
specified config exists in ZooKeeper
[junit4] 2> 288119 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores/collection1/'
[junit4] 2> 288119 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 288119 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 288133 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 288138 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 288142 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.0.0
[junit4] 2> 288152 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.Config Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 288153 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.s.IndexSchema
Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 288158 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 288259 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 288264 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.s.IndexSchema
default search field in schema is text
[junit4] 2> 288265 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.s.IndexSchema
unique key field: id
[junit4] 2> 288277 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 288280 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 288281 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 288282 WARN
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 288282 WARN
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 288282 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 288283 WARN
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 288283 WARN
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 288284 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/configs/conf1',
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
[junit4] 2> 288284 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 288284 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory
[junit4] 2> 288294 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:42096/solr_hdfs_home
[junit4] 2> 288294 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 288295 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/control-001/cores/collection1/],
dataDir=[null]
[junit4] 2> 288295 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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@802de6d
[junit4] 2> 288295 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:42096/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 288317 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
hdfs://localhost:42096/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 288322 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore New index directory detected: old=null
new=hdfs://localhost:42096/solr_hdfs_home/control_collection/core_node1/data/index/
[junit4] 2> 288338 WARN
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [collection1] Solr index directory
'hdfs:/localhost:42096/solr_hdfs_home/control_collection/core_node1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 288338 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:42096/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 288353 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
hdfs://localhost:42096/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 288354 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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@7de1da74
[junit4] 2> 288629 INFO (IPC Server handler 0 on 42096) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:50737 is
added to blk_1073741825_1001{blockUCState=COMMITTED, primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[[DISK]DS-aef57e15-51c3-46a6-bf2c-96ba5749335a:NORMAL:127.0.0.1:50737|RBW],
ReplicaUnderConstruction[[DISK]DS-97a4113a-3b2c-4bc2-92f2-31d96372015d:NORMAL:127.0.0.1:35135|RBW]]}
size 71
[junit4] 2> 288629 INFO (IPC Server handler 1 on 42096) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:35135 is
added to blk_1073741825_1001 size 71
[junit4] 2> 289027 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@b70d3926
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@23fce20f;
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
[junit4] 2> 289028 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 289038 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 289039 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 289039 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58356_
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
hdfs://localhost:42096/solr_hdfs_home/control_collection/core_node1/data/
[junit4] 2> 289039 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 289039 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 289040 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 289040 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289040 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289040 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289040 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289042 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 289043 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 289044 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 289045 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 289050 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 289051 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289051 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 289052 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Initializing HdfsUpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 tlogDfsReplication=2
[junit4] 2> 289080 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 289080 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 289082 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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=1979649289,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 289153 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@b70d3926
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@23fce20f;
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
[junit4] 2> 289153 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 289153 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@146beb94[collection1] main
[junit4] 2> 289153 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289154 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289154 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289155 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 289155 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 289155 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289156 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289157 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289157 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 289159 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 289161 INFO
(searcherExecutor-449-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@146beb94[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 289161 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289161 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 256 version
buckets from index
[junit4] 2> 289161 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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> 289161 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ 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 1515000619842142208
[junit4] 2> 289161 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Took 0.0ms to seed version buckets with highest version
1515000619842142208
[junit4] 2> 289161 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 289162 INFO
(coreLoadExecutor-448-thread-1-processing-n:127.0.0.1:58356_)
[n:127.0.0.1:58356_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 289162 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
Register replica - core:collection1 address:http://127.0.0.1:58356
collection:control_collection shard:shard1
[junit4] 2> 289163 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
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> 289166 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
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/94687538570199044-core_node1-n_0000000000
[junit4] 2> 289167 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
[junit4] 2> 289168 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 289168 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
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> 289168 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:58356/collection1/
[junit4] 2> 289168 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 289168 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:58356/collection1/ has no replicas
[junit4] 2> 289168 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:58356/collection1/ shard1
[junit4] 2> 289169 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] 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> 289169 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 289170 INFO
(zkCallback-230-thread-2-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] 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> 289170 INFO
(zkCallback-230-thread-1-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] 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> 289172 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 289175 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:58356";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 289278 INFO
(zkCallback-230-thread-1-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] 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> 289278 INFO
(zkCallback-230-thread-2-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] 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> 289326 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are http://127.0.0.1:58356/collection1/ and leader is
http://127.0.0.1:58356/collection1/
[junit4] 2> 289326 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58356
[junit4] 2> 289326 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 289326 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 289326 INFO
(coreZkRegister-442-thread-1-processing-n:127.0.0.1:58356_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58356_
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> 289334 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:58356";,
[junit4] 2> "node_name":"127.0.0.1:58356_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 289336 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:58356";,
[junit4] 2> "node_name":"127.0.0.1:58356_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 289438 INFO
(zkCallback-230-thread-1-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] 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> 289438 INFO
(zkCallback-230-thread-2-processing-n:127.0.0.1:58356_) [n:127.0.0.1:58356_
] 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> 289619 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 289620 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 289621 INFO (zkCallback-232-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2e8cbd15
name:ZooKeeperConnection Watcher:127.0.0.1:40220/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 289621 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 289621 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 289621 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 289624 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 289624 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 289624 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 289627 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 289628 INFO (zkCallback-233-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7ffc8167
name:ZooKeeperConnection Watcher:127.0.0.1:40220/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 289628 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 289628 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 289631 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 4
[junit4] 2> 289631 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
collection1
[junit4] 2> 289740 INFO
(OverseerStateUpdate-94687538570199044-127.0.0.1:58356_-n_0000000000)
[n:127.0.0.1:58356_ ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/collection1/state.json
[junit4] 2> 289950 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001/cores/collection1
[junit4] 2> 289951 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001
[junit4] 2> 289952 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 289954 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1ba85db6{/,null,AVAILABLE}
[junit4] 2> 289955 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@46348666{HTTP/1.1}{127.0.0.1:54613}
[junit4] 2> 289955 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.e.j.s.Server Started @293142ms
[junit4] 2> 289955 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:42096/hdfs__localhost_42096__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001_tempDir-002_jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=54613,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001/cores}
[junit4] 2> 289955 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 289955 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001/'
[junit4] 2> 289956 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 289956 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 289978 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 289979 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 289982 INFO (zkCallback-234-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2fab4f40
name:ZooKeeperConnection Watcher:127.0.0.1:40220/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 289982 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 289982 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 289988 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 289988 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001/solr.xml
[junit4] 2> 289993 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.CoresLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001/cores
[junit4] 2> 289994 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.CoreContainer New CoreContainer 396859537
[junit4] 2> 289994 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001/]
[junit4] 2> 289994 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.CoreContainer loading shared library:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001/lib
[junit4] 2> 289994 WARN
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to
classloader: lib (resolved as:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_B49F89C8FEBF92DB-001/shard-1-001/lib).
[junit4] 2> 290001 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
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> 290003 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 290003 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 290003 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 290004 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40220/solr
[junit4] 2> 290004 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 290004 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 290004 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[B49F89C8FEBF92DB]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 290006 INFO (zkCallback-236-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2196679f name:ZooKeeperConnecti
[...truncated too long message...]
> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3851210 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[B49F89C8FEBF92DB])
[ ] o.e.j.s.ServerConnector Stopped
ServerConnector@79b6d2bb{HTTP/1.1}{127.0.0.1:40698}
[junit4] 2> 3851210 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[B49F89C8FEBF92DB])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3e99ad48{/,null,UNAVAILABLE}
[junit4] 2> 3851212 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[B49F89C8FEBF92DB])
[n:127.0.0.1:40698_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:49063 49063
[junit4] 2> 3851396 INFO (Thread-43888) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:49063 49063
[junit4] 2> 3851397 WARN (Thread-43888) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 6 /solr/security.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 35 /solr/clusterstate.json
[junit4] 2> 3
/solr/overseer_elect/election/94687748503830532-127.0.0.1:54250_-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 11 /solr/live_nodes
[junit4] 2> 8 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2>
[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=LeaderInitiatedRecoveryOnShardRestartTest
-Dtests.method=testRestartWithAllInLIR -Dtests.seed=B49F89C8FEBF92DB
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=it_IT -Dtests.timezone=Singapore -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 362s J2 |
LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes
recoverying - waited for 330 seconds
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:172)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:128)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:830)
[junit4] > at
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR(LeaderInitiatedRecoveryOnShardRestartTest.java:81)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=51242, name=coreZkRegister-5187-thread-1,
state=RUNNABLE, group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest]
[junit4] > Caused by: java.lang.AssertionError
[junit4] > at
__randomizedtesting.SeedInfo.seed([B49F89C8FEBF92DB]:0)
[junit4] > at
org.apache.solr.cloud.ZkController.updateLeaderInitiatedRecoveryState(ZkController.java:2126)
[junit4] > at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:433)
[junit4] > at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197)
[junit4] > at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157)
[junit4] > at
org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:346)
[junit4] > at
org.apache.solr.cloud.ZkController.joinElection(ZkController.java:1113)
[junit4] > at
org.apache.solr.cloud.ZkController.register(ZkController.java:926)
[junit4] > at
org.apache.solr.cloud.ZkController.register(ZkController.java:881)
[junit4] > at
org.apache.solr.core.ZkContainer$2.run(ZkContainer.java:183)
[junit4] > at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 3851405 INFO
(SUITE-LeaderInitiatedRecoveryOnShardRestartTest-seed#[B49F89C8FEBF92DB]-worker)
[n:127.0.0.1:40698_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_B49F89C8FEBF92DB-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {},
docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {},
locale=it_IT, timezone=Singapore
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_45 (64-bit)/cpus=4,threads=1,free=387846552,total=503840768
[junit4] 2> NOTE: All tests run in this JVM: [StressHdfsTest,
TestWriterPerf, AnalyticsMergeStrategyTest,
DistributedFacetPivotSmallAdvancedTest, TestPerFieldSimilarity,
DistributedQueryComponentCustomSortTest, CoreAdminHandlerTest,
TestScoreJoinQPScore, SharedFSAutoReplicaFailoverUtilsTest,
TestCursorMarkWithoutUniqueKey, CloudMLTQParserTest,
TestDefaultSimilarityFactory, TestFieldSortValues,
BigEndianAscendingWordDeserializerTest, TestSort, PluginInfoTest,
TestExactSharedStatsCache, TestRequestStatusCollectionAPI, TestFieldResource,
RegexBoostProcessorTest, DateRangeFieldTest, TestMissingGroups,
TestDistributedStatsComponentCardinality, DocValuesMultiTest,
TestImplicitCoreProperties, AddBlockUpdateTest, TestConfigReload,
BadComponentTest, TestSmileRequest, TestFuzzyAnalyzedSuggestions,
HLLSerializationTest, TestManagedStopFilterFactory,
DistributedDebugComponentTest, OverriddenZkACLAndCredentialsProvidersTest,
TermVectorComponentDistributedTest, HdfsDirectoryTest, SolrTestCaseJ4Test,
QueryParsingTest, ChangedSchemaMergeTest, QueryResultKeyTest,
TestSearcherReuse, TestReversedWildcardFilterFactory, RAMDirectoryFactoryTest,
TestExtendedDismaxParser, TestSolrCLIRunExample, BasicAuthIntegrationTest,
TestSolrCloudWithKerberosAlt, TestStressReorder, TestCloudManagedSchema,
TestCollectionAPI, TestConfigSetProperties, TestCharFilters, BufferStoreTest,
TestElisionMultitermQuery, TestSolrIndexConfig, TestShardHandlerFactory,
PrimitiveFieldTypeTest, CdcrUpdateLogTest, ShowFileRequestHandlerTest,
TestCoreDiscovery, TestSolrQueryParser,
ConcurrentDeleteAndCreateCollectionTest, TestAddFieldRealTimeGet,
TestClassNameShortening, ZkNodePropsTest, UUIDFieldTest,
TestPostingsSolrHighlighter, TestXIncludeConfig, HdfsChaosMonkeySafeLeaderTest,
HdfsDirectoryFactoryTest, AddSchemaFieldsUpdateProcessorFactoryTest,
UpdateParamsTest, TestJsonFacets, TestRandomRequestDistribution,
DistributedFacetPivotSmallTest, PKIAuthenticationIntegrationTest,
TestFastWriter, AutoCommitTest, DeleteReplicaTest,
TestLeaderInitiatedRecoveryThread, SearchHandlerTest, BJQParserTest,
NoCacheHeaderTest, TestLRUCache, SuggesterWFSTTest, TestBulkSchemaConcurrent,
TestTrieFacet, TestPHPSerializedResponseWriter,
SuggestComponentContextFilterQueryTest, BasicDistributedZkTest,
BasicDistributedZk2Test, UnloadDistributedZkTest,
CollectionsAPIDistributedZkTest, TestBinaryResponseWriter,
HighlighterConfigTest, TestQuerySenderListener, CopyFieldTest,
ResponseLogComponentTest, SolrIndexConfigTest, TestStressRecovery,
DistributedIntervalFacetingTest, CollectionTooManyReplicasTest,
LeaderInitiatedRecoveryOnShardRestartTest]
[junit4] Completed [443/552] on J2 in 361.83s, 1 test, 1 error <<< FAILURES!
[...truncated 364 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987:
There were test failures: 552 suites (8 ignored), 2170 tests, 3 errors, 1
failure, 30 ignored (17 assumptions) [seed: B49F89C8FEBF92DB]
Total time: 194 minutes 28 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]
