Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/970/
3 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest
Error Message:
ObjectTracker found 2 object(s) that were not released!!! [HdfsTransactionLog,
HdfsTransactionLog]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 2 object(s) that were not
released!!! [HdfsTransactionLog, HdfsTransactionLog]
at __randomizedtesting.SeedInfo.seed([296E9614A96AEF4F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:238)
at sun.reflect.GeneratedMethodAccessor44.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:47332
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting
response from server at: http://127.0.0.1:47332
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:381)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:508)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:169)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.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:482)
... 47 more
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=28203, name=collection0,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=28203, name=collection0, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:39866: collection already exists:
awholynewstresscollection_collection0_0
at __randomizedtesting.SeedInfo.seed([296E9614A96AEF4F]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:970)
Build Log:
[...truncated 11424 lines...]
[junit4] Suite:
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/init-core-data-001
[junit4] 2> 428270 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 428271 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 428336 WARN
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 428347 WARN
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 428354 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 428376 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-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_48972_hdfs____.48prba/webapp
[junit4] 2> 428492 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log NO JSP Support for /, did not find
org.apache.jasper.servlet.JspServlet
[junit4] 2> 428854 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:48972
[junit4] 2> 429093 WARN
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 429100 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 429124 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-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_52043_datanode____.td7eav/webapp
[junit4] 2> 429304 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log NO JSP Support for /, did not find
org.apache.jasper.servlet.JspServlet
[junit4] 2> 429553 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:52043
[junit4] 2> 429661 WARN
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 429673 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 429707 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-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_58695_datanode____i217zq/webapp
[junit4] 2> 429797 INFO (IPC Server handler 4 on 36316) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-ce26d2a5-e59d-48ed-aba7-a748a759a36e node DatanodeRegistration(127.0.0.1,
datanodeUuid=ba7217d9-1e2a-4eac-8363-eede72dd0b18, infoPort=52043,
ipcPort=36000, storageInfo=lv=-56;cid=testClusterID;nsid=608370216;c=0),
blocks: 0, hasStaleStorages: true, processing time: 2 msecs
[junit4] 2> 429797 INFO (IPC Server handler 4 on 36316) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-9d677481-d907-49d5-adae-8453ef2011a2 node DatanodeRegistration(127.0.0.1,
datanodeUuid=ba7217d9-1e2a-4eac-8363-eede72dd0b18, infoPort=52043,
ipcPort=36000, storageInfo=lv=-56;cid=testClusterID;nsid=608370216;c=0),
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
[junit4] 2> 429905 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log NO JSP Support for /, did not find
org.apache.jasper.servlet.JspServlet
[junit4] 2> 430163 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[296E9614A96AEF4F]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58695
[junit4] 2> 430279 INFO (IPC Server handler 3 on 36316) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-e0725584-bc60-40c2-9f20-56bc04fe17f8 node DatanodeRegistration(127.0.0.1,
datanodeUuid=4b1c7387-f278-48fb-a645-afa48a354d49, infoPort=58695,
ipcPort=59548, storageInfo=lv=-56;cid=testClusterID;nsid=608370216;c=0),
blocks: 0, hasStaleStorages: true, processing time: 1 msecs
[junit4] 2> 430279 INFO (IPC Server handler 3 on 36316) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-06128175-6df1-4721-a3e9-f02c1a9069cf node DatanodeRegistration(127.0.0.1,
datanodeUuid=4b1c7387-f278-48fb-a645-afa48a354d49, infoPort=58695,
ipcPort=59548, storageInfo=lv=-56;cid=testClusterID;nsid=608370216;c=0),
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
[junit4] 2> 430344 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 430345 INFO (Thread-2538) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 430345 INFO (Thread-2538) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 430445 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.ZkTestServer start zk server on port:54854
[junit4] 2> 430445 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 430446 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 430449 INFO (zkCallback-380-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@30cb6813
name:ZooKeeperConnection Watcher:127.0.0.1:54854 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 430450 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 430451 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 430451 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 430457 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 430462 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 430462 INFO (zkCallback-381-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4042e7bc
name:ZooKeeperConnection Watcher:127.0.0.1:54854/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 430462 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 430463 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 430463 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 430464 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 430466 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 430467 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 430468 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 430468 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 430471 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 430471 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 430472 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 430473 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 430474 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 430474 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 430475 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 430476 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 430477 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 430477 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 430478 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 430479 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 430481 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 430481 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 430483 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 430484 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 430491 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 430491 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 430497 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 430497 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 430600 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/cores/collection1
[junit4] 2> 430602 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 430603 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@199024be{/,null,AVAILABLE}
[junit4] 2> 430606 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@4bec12d{HTTP/1.1,[http/1.1]}{127.0.0.1:60297}
[junit4] 2> 430606 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.e.j.s.Server Started @433759ms
[junit4] 2> 430606 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:36316/hdfs__localhost_36316__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001_tempDir-002_control_data,
hostContext=/, hostPort=60297,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/cores}
[junit4] 2> 430606 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 430606 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001'
[junit4] 2> 430607 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 430607 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 430607 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 430610 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 430615 INFO (zkCallback-382-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2ed1536c
name:ZooKeeperConnection Watcher:127.0.0.1:54854/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 430615 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 430615 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 430617 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 430617 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/solr.xml
[junit4] 2> 430623 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/cores
[junit4] 2> 430623 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1361038551
[junit4] 2> 430623 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001]
[junit4] 2> 430624 WARN
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/lib
[junit4] 2> 430624 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
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> 430625 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 430633 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 430633 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 430633 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54854/solr
[junit4] 2> 430633 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 430633 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 430636 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 430638 INFO (zkCallback-385-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5d36aae4
name:ZooKeeperConnection Watcher:127.0.0.1:54854 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 430639 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 430639 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 430642 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 430643 INFO
(zkCallback-386-thread-1-processing-n:127.0.0.1:60297_) [n:127.0.0.1:60297_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@75f3db5c
name:ZooKeeperConnection Watcher:127.0.0.1:54854/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 430643 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 430644 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 430646 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 430649 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 430650 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 430652 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 430653 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 430654 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 430655 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 430655 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 430656 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 430659 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (0)
[junit4] 2> 430660 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 430661 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 430662 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 430663 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/95606044575137796-127.0.0.1:60297_-n_0000000000
[junit4] 2> 430663 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:60297_
[junit4] 2> 430663 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 430664 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.Overseer Overseer
(id=95606044575137796-127.0.0.1:60297_-n_0000000000) starting
[junit4] 2> 430665 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 430670 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 430671 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60297_
[junit4] 2> 430671 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:60297_
[junit4] 2> 430674 INFO
(OverseerCollectionConfigSetProcessor-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] o.a.s.c.OverseerTaskProcessor Process current queue
of overseer operations
[junit4] 2> 430675 INFO
(zkCallback-386-thread-1-processing-n:127.0.0.1:60297_) [n:127.0.0.1:60297_
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [0])
[junit4] 2> 430675 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 430686 INFO
(zkCallback-386-thread-1-processing-n:127.0.0.1:60297_) [n:127.0.0.1:60297_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 430691 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (1)
[junit4] 2> 430701 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 430701 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 430702 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/cores
[junit4] 2> 430703 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
collection=control_collection, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 430703 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.CorePropertiesLocator Found core collection1
in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/cores/collection1
[junit4] 2> 430703 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 430704 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 430704 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 430704 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1
[junit4] 2> 430705 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:60297_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 430707 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] 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:60297";,
[junit4] 2> "node_name":"127.0.0.1:60297_",
[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> 430707 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 430709 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] 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:60297";,
[junit4] 2> "node_name":"127.0.0.1:60297_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 430710 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
control_collection
[junit4] 2> 430716 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 430719 INFO
(zkCallback-386-thread-1-processing-n:127.0.0.1:60297_) [n:127.0.0.1:60297_
] 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> 431710 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 431710 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.ZkController
Check for collection zkNode:control_collection
[junit4] 2> 431711 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 431711 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 431712 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 431712 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/cores/collection1'
[junit4] 2> 431712 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 431712 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 431714 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 431728 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 431734 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 431754 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 431767 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 431941 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 431955 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.s.IndexSchema
default search field in schema is text
[junit4] 2> 431962 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.s.IndexSchema
unique key field: id
[junit4] 2> 431982 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 431984 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 431986 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 431986 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 431988 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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-master/solr/build/solr-core/test/J1
[junit4] 2> 431988 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 431988 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory
[junit4] 2> 431988 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:36316/solr_hdfs_home
[junit4] 2> 431988 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 431988 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/control-001/cores/collection1],
dataDir=[hdfs://localhost:36316/solr_hdfs_home/control_collection/core_node1/data/]
[junit4] 2> 431988 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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@5a9c3c0d
[junit4] 2> 431989 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:36316/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 432019 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
hdfs://localhost:36316/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 432020 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore New index directory detected: old=null
new=hdfs://localhost:36316/solr_hdfs_home/control_collection/core_node1/data/index/
[junit4] 2> 432054 WARN
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [collection1] Solr index directory
'hdfs:/localhost:36316/solr_hdfs_home/control_collection/core_node1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 432054 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:36316/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 432080 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
hdfs://localhost:36316/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 432081 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.28199693402384496]
[junit4] 2> 432117 INFO (IPC Server handler 3 on 36316) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60951 is
added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION,
primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[[DISK]DS-9d677481-d907-49d5-adae-8453ef2011a2:NORMAL:127.0.0.1:53331|RBW],
ReplicaUnderConstruction[[DISK]DS-e0725584-bc60-40c2-9f20-56bc04fe17f8:NORMAL:127.0.0.1:60951|FINALIZED]]}
size 0
[junit4] 2> 432119 INFO (IPC Server handler 6 on 36316) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53331 is
added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION,
primaryNodeIndex=-1,
replicas=[ReplicaUnderConstruction[[DISK]DS-9d677481-d907-49d5-adae-8453ef2011a2:NORMAL:127.0.0.1:53331|RBW],
ReplicaUnderConstruction[[DISK]DS-e0725584-bc60-40c2-9f20-56bc04fe17f8:NORMAL:127.0.0.1:60951|FINALIZED]]}
size 0
[junit4] 2> 432128 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=NRTCachingDirectory(HdfsDirectory@hdfs://localhost:36316/solr_hdfs_home/control_collection/core_node1/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5781f4fa;
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
[junit4] 2> 432128 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 432141 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 432141 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 432141 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 432141 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 432141 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 432141 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432142 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432142 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432142 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432142 WARN
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 432144 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 432145 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 432146 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 432146 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 432150 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 432150 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432150 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60297_
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:36316/solr_hdfs_home/control_collection/core_node1/data/
[junit4] 2> 432162 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432162 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 432162 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 432182 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 432182 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 432184 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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@3d063ff
[junit4] 2> 432227 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=NRTCachingDirectory(HdfsDirectory@hdfs://localhost:36316/solr_hdfs_home/control_collection/core_node1/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5781f4fa;
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
[junit4] 2> 432227 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 432227 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@184f7877[collection1] main]
[junit4] 2> 432228 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432230 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432230 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432234 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 432234 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 432234 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432238 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432238 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432238 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 432238 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 432241 INFO
(searcherExecutor-1236-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@184f7877[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 432241 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432241 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version
buckets from index
[junit4] 2> 432241 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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> 432241 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ 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 1529696715181916160
[junit4] 2> 432243 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version
1529696715181916160
[junit4] 2> 432243 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 432247 INFO
(coreLoadExecutor-1235-thread-1-processing-n:127.0.0.1:60297_)
[n:127.0.0.1:60297_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 432248 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
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:60297
collection:control_collection shard:shard1
[junit4] 2> 432251 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
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> 432254 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
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/95606044575137796-core_node1-n_0000000000
[junit4] 2> 432254 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1
and weAreReplacement=false and leaderVoteWait=180000
[junit4] 2> 432256 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 432256 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
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> 432256 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:60297/collection1/
[junit4] 2> 432256 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 432256 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:60297/collection1/ has no replicas
[junit4] 2> 432256 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 432258 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/control_collection/leaders/shard1/leader after winning as
/collections/control_collection/leader_elect/shard1/election/95606044575137796-core_node1-n_0000000000
[junit4] 2> 432259 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] 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> 432260 INFO
(zkCallback-386-thread-1-processing-n:127.0.0.1:60297_) [n:127.0.0.1:60297_
] 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> 432263 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
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:60297/collection1/ shard1
[junit4] 2> 432266 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] 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:60297";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 432368 INFO
(zkCallback-386-thread-1-processing-n:127.0.0.1:60297_) [n:127.0.0.1:60297_
] 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> 432417 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are http://127.0.0.1:60297/collection1/ and leader is
http://127.0.0.1:60297/collection1/
[junit4] 2> 432417 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
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:60297
[junit4] 2> 432417 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 432417 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 432417 INFO
(coreZkRegister-1229-thread-1-processing-n:127.0.0.1:60297_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60297_
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> 432419 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] 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:60297";,
[junit4] 2> "node_name":"127.0.0.1:60297_",
[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> 432419 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] 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:60297";,
[junit4] 2> "node_name":"127.0.0.1:60297_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 432521 INFO
(zkCallback-386-thread-1-processing-n:127.0.0.1:60297_) [n:127.0.0.1:60297_
] 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> 432708 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 432710 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 432710 INFO (zkCallback-388-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3eb8bc3b
name:ZooKeeperConnection Watcher:127.0.0.1:54854/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 432710 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 432711 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 432711 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 432712 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 432712 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 432817 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001/cores/collection1
[junit4] 2> 432818 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001
[junit4] 2> 432819 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 432820 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@47346c3c{/,null,AVAILABLE}
[junit4] 2> 432821 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@add11b5{HTTP/1.1,[http/1.1]}{127.0.0.1:39630}
[junit4] 2> 432821 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.e.j.s.Server Started @435974ms
[junit4] 2> 432821 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:36316/hdfs__localhost_36316__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001_tempDir-002_jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=39630,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001/cores}
[junit4] 2> 432821 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 432821 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001'
[junit4] 2> 432821 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 432821 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 432822 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 432831 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 432836 INFO (zkCallback-389-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@23925b6f
name:ZooKeeperConnection Watcher:127.0.0.1:54854/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 432836 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 432836 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 432838 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 432838 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001/solr.xml
[junit4] 2> 432844 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001/cores
[junit4] 2> 432844 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.CoreContainer New CoreContainer 409226556
[junit4] 2> 432844 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001]
[junit4] 2> 432844 WARN
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_296E9614A96AEF4F-001/shard-1-001/lib
[junit4] 2> 432845 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
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> 432846 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 432846 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 432846 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 432846 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54854/solr
[junit4] 2> 432847 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 432847 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 432853 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 432854 INFO (zkCallback-392-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@46c9566e
name:ZooKeeperConnection Watcher:127.0.0.1:54854 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 432854 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 432855 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 432862 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:39630_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 432863 INFO
(zkCallback-393-thread-1-processing-n:127.0.0.1:39630_) [n:127.0.0.1:39630_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2a3b18e1
name:ZooKeeperConnection Watcher:127.0.0.1:54854/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 432863 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:39630_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 432877 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:39630_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 432879 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:39630_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 432900 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:39630_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 432900 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:39630_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/95606044575137800-127.0.0.1:39630_-n_0000000001
[junit4] 2> 432904 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:39630_ ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/95606044575137796-127.0.0.1:60297_-n_0000000000 to
know if I could be the leader
[junit4] 2> 432904 INFO
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[296E9614A96AEF4F])
[n:127.0.0.1:39630_ ] o.a.s.c.ZkController Publish node=127.0.0.1:39630_ as
DOWN
[junit4] 2> 432906 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:39630_"} current state version: 4
[junit4] 2> 432906 INFO
(OverseerStateUpdate-95606044575137796-127.0.0.1:60297_-n_0000000000)
[n:127.0.0.1:60297_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node:
127.0.0.1:39630_
[junit4] 2> 433008 INFO (zkCallback-388-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [1])
[junit4] 2> 433008 INFO
(zkCallback-386-thread-1-processing-n:127.0.0.1:60297_) [n:127.0.0.1:60297_
] 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> 433009 INFO
(zkCallback-393-thread-1-processing-n:127.0.0.1:39630_) [n:127.0.0.1:39630_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (l
[...truncated too long message...]
l.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2>
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at
org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:130)
[junit4] 2> at
org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:74)
[junit4] 2> at
org.apache.solr.update.HdfsUpdateLog.ensureLog(HdfsUpdateLog.java:308)
[junit4] 2> at
org.apache.solr.update.UpdateLog.delete(UpdateLog.java:466)
[junit4] 2> at
org.apache.solr.update.DirectUpdateHandler2.delete(DirectUpdateHandler2.java:384)
[junit4] 2> at
org.apache.solr.update.processor.RunUpdateProcessor.processDelete(RunUpdateProcessorFactory.java:76)
[junit4] 2> at
org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:52)
[junit4] 2> at
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalDelete(DistributedUpdateProcessor.java:941)
[junit4] 2> at
org.apache.solr.update.processor.DistributedUpdateProcessor.versionDelete(DistributedUpdateProcessor.java:1586)
[junit4] 2> at
org.apache.solr.update.processor.DistributedUpdateProcessor.processDelete(DistributedUpdateProcessor.java:1151)
[junit4] 2> at
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processDelete(LogUpdateProcessorFactory.java:124)
[junit4] 2> at
org.apache.solr.handler.loader.JavabinLoader.delete(JavabinLoader.java:151)
[junit4] 2> at
org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:112)
[junit4] 2> at
org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:54)
[junit4] 2> at
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
[junit4] 2> at
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:69)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155)
[junit4] 2> at
org.apache.solr.core.SolrCore.execute(SolrCore.java:2037)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:652)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:229)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:184)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:399)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> Mar 24, 2016 3:56:10 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60):
{rnd_b=PostingsFormat(name=SimpleText),
_version_=PostingsFormat(name=Asserting), a_t=PostingsFormat(name=Asserting),
a_i=PostingsFormat(name=SimpleText), id=PostingsFormat(name=SimpleText)},
docValues:{}, maxPointsInLeafNode=1773, maxMBSortInHeap=6.077557835566116,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=fr-CA,
timezone=America/St_Johns
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_74 (64-bit)/cpus=4,threads=2,free=65368880,total=526909440
[junit4] 2> NOTE: All tests run in this JVM: [TestCryptoKeys,
CustomCollectionTest, TestBadConfig, DistributedSpellCheckComponentTest,
TestValueSourceCache, TestConfigSetImmutable, TestRTGBase,
CollectionTooManyReplicasTest, ReturnFieldsTest, XsltUpdateRequestHandlerTest,
TestAnalyzedSuggestions, HdfsBasicDistributedZk2Test, SynonymTokenizerTest,
TestStressLucene, SignatureUpdateProcessorFactoryTest,
ParsingFieldUpdateProcessorsTest, TestFaceting, DistribCursorPagingTest,
GraphQueryTest, TestUniqueKeyFieldResource, MergeStrategyTest,
BigEndianAscendingWordDeserializerTest, AnalysisAfterCoreReloadTest,
TestRTimerTree, SyncSliceTest, TestChildDocTransformer, TestFastOutputStream,
RAMDirectoryFactoryTest, TestRawResponseWriter, DistributedFacetPivotLargeTest,
DistributedQueryElevationComponentTest,
TestOverriddenPrefixQueryForCustomFieldType, TestRangeQuery,
BlockDirectoryTest, TestSchemaResource, TestReplicationHandler,
SaslZkACLProviderTest, TestClusterStateMutator,
TlogReplayBufferedWhileIndexingTest, TestLRUStatsCache,
CachingDirectoryFactoryTest, CoreMergeIndexesAdminHandlerTest, TestCSVLoader,
TestReloadDeadlock, HttpPartitionTest, URLClassifyProcessorTest,
JsonLoaderTest, BadCopyFieldTest, LeaderInitiatedRecoveryOnShardRestartTest,
ResourceLoaderTest, TestJoin, TestDistributedSearch,
TestDistributedStatsComponentCardinality, PathHierarchyTokenizerFactoryTest,
TestSolr4Spatial, TestDocSet, TestRandomFaceting,
CurrencyFieldOpenExchangeTest, DefaultValueUpdateProcessorTest,
PingRequestHandlerTest, RequiredFieldsTest, ActionThrottleTest,
TestClassicSimilarityFactory, TestShardHandlerFactory,
SegmentsInfoRequestHandlerTest, TestFreeTextSuggestions,
IndexSchemaRuntimeFieldTest, RestartWhileUpdatingTest, TestReplicaProperties,
TestHighlightDedupGrouping, HdfsChaosMonkeySafeLeaderTest]
[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=HdfsChaosMonkeySafeLeaderTest -Dtests.seed=296E9614A96AEF4F
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=fr-CA -Dtests.timezone=America/St_Johns -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | HdfsChaosMonkeySafeLeaderTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 2
object(s) that were not released!!! [HdfsTransactionLog, HdfsTransactionLog]
[junit4] > at
__randomizedtesting.SeedInfo.seed([296E9614A96AEF4F]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:238)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [272/581 (3!)] on J0 in 190.54s, 1 test, 1 failure <<<
FAILURES!
[...truncated 983 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:747:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:684:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/common-build.xml:529:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1457:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1014:
There were test failures: 581 suites (10 ignored), 2324 tests, 1 suite-level
error, 2 errors, 32 ignored (16 assumptions) [seed: 296E9614A96AEF4F]
Total time: 246 minutes 53 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
