Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/787/
3 tests failed. REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=49918, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=49918, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:40713/do/v: Could not find collection : awholynewstresscollection_collection2_0 at __randomizedtesting.SeedInfo.seed([BC57C84A84422A21]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) REGRESSION: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test Error Message: Captured an uncaught exception in thread: Thread[id=39048, name=Thread-29455, state=RUNNABLE, group=TGRP-TestDistributedStatsComponentCardinality] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=39048, name=Thread-29455, state=RUNNABLE, group=TGRP-TestDistributedStatsComponentCardinality] at __randomizedtesting.SeedInfo.seed([BC57C84A84422A21:3403F7902ABE47D9]:0) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51970//collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://[ff01::114]:33332, http://127.0.0.1:38606//collection1, http://[ff01::083]:33332, http://[ff01::213]:33332] at __randomizedtesting.SeedInfo.seed([BC57C84A84422A21]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958) at org.apache.solr.BaseDistributedSearchTestCase$5.run(BaseDistributedSearchTestCase.java:624) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([BC57C84A84422A21:3403F7902ABE47D9]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.junit.Assert.assertNull(Assert.java:562) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testNoConfigSetExist(CollectionsAPIDistributedZkTest.java:519) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:166) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9693 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/init-core-data-001 [junit4] 2> 39080 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) [junit4] 2> 39081 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /e_/b [junit4] 2> 41117 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your platform... using builtin-java classes where applicable [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 42921 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 43565 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog [junit4] 2> 43591 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 43759 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 43861 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-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_57999_hdfs____.dvp0kf/webapp [junit4] 2> 44275 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 45328 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:57999 [junit4] 2> 47918 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 47936 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 47970 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-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_34388_datanode____.2w75if/webapp [junit4] 2> 48103 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 48517 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:34388 [junit4] 2> 48841 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 48844 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 48902 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-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_58963_datanode____.ef5rom/webapp [junit4] 2> 49205 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 49841 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BC57C84A84422A21]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58963 [junit4] 2> 53166 INFO (IPC Server handler 0 on 35390) [ ] BlockStateChange BLOCK* processReport: from storage DS-3e003bed-79c0-4cb2-946b-8c820412a82f node DatanodeRegistration(127.0.0.1, datanodeUuid=eab61fbe-7de5-4841-87ae-8d33a0164a4d, infoPort=34388, ipcPort=40618, storageInfo=lv=-56;cid=testClusterID;nsid=577351837;c=0), blocks: 0, hasStaleStorages: true, processing time: 13 msecs [junit4] 2> 53174 INFO (IPC Server handler 0 on 35390) [ ] BlockStateChange BLOCK* processReport: from storage DS-f0d2389b-6010-4096-870d-88cf388c101d node DatanodeRegistration(127.0.0.1, datanodeUuid=eab61fbe-7de5-4841-87ae-8d33a0164a4d, infoPort=34388, ipcPort=40618, storageInfo=lv=-56;cid=testClusterID;nsid=577351837;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 53189 INFO (IPC Server handler 3 on 35390) [ ] BlockStateChange BLOCK* processReport: from storage DS-8f10944a-482f-4cec-8743-d4c214721bf1 node DatanodeRegistration(127.0.0.1, datanodeUuid=f7938484-b061-4915-8f05-2da051b57bd8, infoPort=58963, ipcPort=55617, storageInfo=lv=-56;cid=testClusterID;nsid=577351837;c=0), blocks: 0, hasStaleStorages: true, processing time: 8 msecs [junit4] 2> 53191 INFO (IPC Server handler 3 on 35390) [ ] BlockStateChange BLOCK* processReport: from storage DS-8ee38728-ca06-4e07-944a-dd30d53f753d node DatanodeRegistration(127.0.0.1, datanodeUuid=f7938484-b061-4915-8f05-2da051b57bd8, infoPort=58963, ipcPort=55617, storageInfo=lv=-56;cid=testClusterID;nsid=577351837;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 54387 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 54470 INFO (Thread-213) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 54470 INFO (Thread-213) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 54569 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.ZkTestServer start zk server on port:48961 [junit4] 2> 54591 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 54749 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 54829 INFO (zkCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@697bbb2e name:ZooKeeperConnection Watcher:127.0.0.1:48961 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 54835 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 54836 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 54838 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 54896 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 54919 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 54949 INFO (zkCallback-17-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3409b548 name:ZooKeeperConnection Watcher:127.0.0.1:48961/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 54950 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 54950 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 54951 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 54967 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 54971 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 54973 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 54978 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 54979 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 54984 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 54985 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 55004 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 55004 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 55008 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 55009 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 55012 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 55012 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 55015 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 55015 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 55020 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 55020 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 55024 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 55024 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 55040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 55041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 55051 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 55052 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 55061 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 55061 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 55086 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 55108 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 55137 INFO (zkCallback-18-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c0ebbab name:ZooKeeperConnection Watcher:127.0.0.1:48961 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 55137 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 55138 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 55138 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 55154 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 55181 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 55202 INFO (zkCallback-19-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2ea561 name:ZooKeeperConnection Watcher:127.0.0.1:48961/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 55202 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 55203 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 55203 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 55204 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 55212 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 55212 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 55218 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 55219 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 55223 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 55224 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 55231 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 55232 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 55234 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 55235 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 55238 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 55238 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 55254 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 55254 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 55257 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 55257 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 55262 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 55263 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 55265 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 55266 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 55278 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 55279 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 56186 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores/collection1 [junit4] 2> 56189 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 56192 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@47369c64{/e_/b,null,AVAILABLE} [junit4] 2> 56192 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5204ff2e{HTTP/1.1}{127.0.0.1:49420} [junit4] 2> 56193 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.e.j.s.Server Started @61239ms [junit4] 2> 56194 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:35390/hdfs__localhost_35390__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001_tempDir-002_control_data, hostContext=/e_/b, hostPort=49420, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores} [junit4] 2> 56195 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 56195 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/' [junit4] 2> 56211 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 56212 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 56225 INFO (zkCallback-20-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@73304c25 name:ZooKeeperConnection Watcher:127.0.0.1:48961/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 56225 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 56226 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 56229 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 56229 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/solr.xml [junit4] 2> 56253 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores [junit4] 2> 56254 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.CoreContainer New CoreContainer 79766839 [junit4] 2> 56254 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/] [junit4] 2> 56254 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/lib [junit4] 2> 56254 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/lib). [junit4] 2> 56263 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] 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> 56276 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 56276 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 56277 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 56277 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48961/solr [junit4] 2> 56302 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 56302 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 56317 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 56341 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3061776c name:ZooKeeperConnection Watcher:127.0.0.1:48961 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 56342 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 56342 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 56367 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 56370 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@64578216 name:ZooKeeperConnection Watcher:127.0.0.1:48961/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 56370 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 56380 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 56389 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 56395 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 56400 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 56403 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 56417 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 56420 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 56422 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 56425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 56426 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49420_e_%2Fb [junit4] 2> 56429 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:49420_e_%2Fb [junit4] 2> 56436 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 56438 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 56447 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 56448 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000 [junit4] 2> 56454 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49420_e_%2Fb [junit4] 2> 56454 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 56457 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.Overseer Overseer (id=94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) starting [junit4] 2> 56465 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 56506 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 56510 INFO (OverseerCollectionConfigSetProcessor-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 56512 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 56513 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 56540 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 56596 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 56597 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 56598 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores [junit4] 2> 56602 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 56602 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores/collection1/ [junit4] 2> 56602 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 56605 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 56605 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 56628 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 56628 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 56630 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] 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:49420/e_/b";, [junit4] 2> "node_name":"127.0.0.1:49420_e_%2Fb", [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> 56637 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 56643 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] 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:49420/e_/b";, [junit4] 2> "node_name":"127.0.0.1:49420_e_%2Fb", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 56649 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 56686 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 56693 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] 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> 56715 INFO (zkCallback-23-thread-2-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] 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> 57638 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 57638 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 57641 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 57642 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 57643 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 57643 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores/collection1/' [junit4] 2> 57672 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 57707 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 57712 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 57755 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 57764 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 57791 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 58048 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 58060 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 58061 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 58105 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 58111 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 58131 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 58134 WARN (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 58134 WARN (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 58134 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 58138 WARN (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 58138 WARN (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 58139 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 58140 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 58140 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 58186 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:35390/solr_hdfs_home [junit4] 2> 58186 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 58186 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 58187 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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@3646a07c [junit4] 2> 58209 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:35390/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 58362 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:35390/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 58362 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:35390/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 58379 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:35390/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 58421 WARN (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:35390/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 58421 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:35390/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 58453 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:35390/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 58454 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:35390/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 58454 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=5, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8697642508094462] [junit4] 2> 58904 INFO (IPC Server handler 5 on 35390) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58252 is added to blk_1073741825_1001{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-f0d2389b-6010-4096-870d-88cf388c101d:NORMAL:127.0.0.1:36333|RBW], ReplicaUnderConstruction[[DISK]DS-8f10944a-482f-4cec-8743-d4c214721bf1:NORMAL:127.0.0.1:58252|RBW]]} size 71 [junit4] 2> 59113 INFO (IPC Server handler 2 on 35390) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36333 is added to blk_1073741825_1001 size 71 [junit4] 2> 59520 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@b645ca7c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c84fc0d; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 59520 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 59532 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 59533 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:49420_e_%2Fb 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:35390/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 59543 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 59544 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 59544 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 59544 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 59544 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59544 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59545 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59545 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59547 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 59559 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 59562 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 59567 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 59596 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 59597 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59603 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59603 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing HdfsUpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 tlogDfsReplication=2 [junit4] 2> 59642 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 59642 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 59645 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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@74ce37b1 [junit4] 2> 59749 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@b645ca7c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c84fc0d; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 59750 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 59750 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@271e9936[collection1] main [junit4] 2> 59750 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59751 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59752 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59754 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 59754 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 59755 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59757 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59757 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59757 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 59757 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 59762 INFO (searcherExecutor-142-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@271e9936[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 59773 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59773 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 256 version buckets from index [junit4] 2> 59773 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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> 59773 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb 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 1511558413761380352 [junit4] 2> 59774 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 0.0ms to seed version buckets with highest version 1511558413761380352 [junit4] 2> 59775 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 59777 INFO (coreLoadExecutor-141-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 59788 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb 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:49420/e_/b collection:control_collection shard:shard1 [junit4] 2> 59800 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb 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> 59816 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb 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/94472400519757830-core_node1-n_0000000000 [junit4] 2> 59817 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 59824 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] 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> 59825 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 59825 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb 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> 59827 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49420/e_/b/collection1/ [junit4] 2> 59828 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 59828 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:49420/e_/b/collection1/ has no replicas [junit4] 2> 59828 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb 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:49420/e_/b/collection1/ shard1 [junit4] 2> 59829 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 59829 INFO (zkCallback-23-thread-2-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] 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> 59829 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] 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> 59862 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 59882 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] 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:49420/e_/b";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 59987 INFO (zkCallback-23-thread-3-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] 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> 59987 INFO (zkCallback-23-thread-2-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] 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> 60033 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:49420/e_/b/collection1/ and leader is http://127.0.0.1:49420/e_/b/collection1/ [junit4] 2> 60034 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb 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:49420/e_/b [junit4] 2> 60034 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 60034 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 60034 INFO (coreZkRegister-135-thread-1-processing-n:127.0.0.1:49420_e_%2Fb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49420_e_%2Fb 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> 60039 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] 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:49420/e_/b";, [junit4] 2> "node_name":"127.0.0.1:49420_e_%2Fb", [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> 60042 INFO (OverseerStateUpdate-94472400519757830-127.0.0.1:49420_e_%2Fb-n_0000000000) [n:127.0.0.1:49420_e_%2Fb ] 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:49420/e_/b";, [junit4] 2> "node_name":"127.0.0.1:49420_e_%2Fb", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 60149 INFO (zkCallback-23-thread-3-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] 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> 60149 INFO (zkCallback-23-thread-2-processing-n:127.0.0.1:49420_e_%2Fb) [n:127.0.0.1:49420_e_%2Fb ] 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> 60150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 60151 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 60160 INFO (zkCallback-25-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4399d890 name:ZooKeeperConnection Watcher:127.0.0.1:48961/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 60160 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 60160 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 60161 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 60171 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 60566 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/shard-1-001/cores/collection1 [junit4] 2> 60567 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/shard-1-001 [junit4] 2> 60573 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 60579 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1394de9a{/e_/b,null,AVAILABLE} [junit4] 2> 60580 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.e.j.s.ServerConnector Started ServerConnector@32558cbc{HTTP/1.1}{127.0.0.1:36013} [junit4] 2> 60580 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.e.j.s.Server Started @65627ms [junit4] 2> 60580 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:35390/hdfs__localhost_35390__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/e_/b, hostPort=36013, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/shard-1-001/cores} [junit4] 2> 60580 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 60581 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/shard-1-001/' [junit4] 2> 60595 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 60596 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 60600 INFO (zkCallback-26-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@553764a4 name:ZooKeeperConnection Watcher:127.0.0.1:48961/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 60601 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 60601 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 60603 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x14fa225d20f0008, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 60605 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 60606 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/shard-1-001/solr.xml [junit4] 2> 60622 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/shard-1-001/cores [junit4] 2> 60622 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.CoreContainer New CoreContainer 1229695005 [junit4] 2> 60623 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/shard-1-001/] [junit4] 2> 60623 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[BC57C84A84422A21]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_BC57C84A84422A21-001/shard-1-001/lib [junit4] 2> 60623 WARN [...truncated too long message...] 2A21-001/tempDir-001/shard6/cores/collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_BC57C84A84422A21-001/tempDir-001/shard6/cores/collection1/data;done=false>>] [junit4] 2> 3670317 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[BC57C84A84422A21]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_BC57C84A84422A21-001/tempDir-001/shard6/cores/collection1/data [junit4] 2> 3670317 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[BC57C84A84422A21]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_BC57C84A84422A21-001/tempDir-001/shard6/cores/collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_BC57C84A84422A21-001/tempDir-001/shard6/cores/collection1/data/index;done=false>>] [junit4] 2> 3670317 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[BC57C84A84422A21]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_BC57C84A84422A21-001/tempDir-001/shard6/cores/collection1/data/index [junit4] 2> 3670322 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[BC57C84A84422A21]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6a67b05b{/,null,UNAVAILABLE} [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=TestDistributedStatsComponentCardinality -Dtests.method=test -Dtests.seed=BC57C84A84422A21 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=cs_CZ -Dtests.timezone=America/Yellowknife -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 253s J1 | TestDistributedStatsComponentCardinality.test <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=39048, name=Thread-29455, state=RUNNABLE, group=TGRP-TestDistributedStatsComponentCardinality] [junit4] > at __randomizedtesting.SeedInfo.seed([BC57C84A84422A21:3403F7902ABE47D9]:0) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51970//collection1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://[ff01::114]:33332, http://127.0.0.1:38606//collection1, http://[ff01::083]:33332, http://[ff01::213]:33332] [junit4] > at __randomizedtesting.SeedInfo.seed([BC57C84A84422A21]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$5.run(BaseDistributedSearchTestCase.java:624) [junit4] 2> 3670371 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[BC57C84A84422A21]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {int_i=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=FST50, intDefault=PostingsFormat(name=MockRandom), int_i_prehashed_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), long_l_prehashed_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l=FST50, long_l=PostingsFormat(name=LuceneVarGapFixedInterval), _version_=PostingsFormat(name=MockRandom), string_s_prehashed_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), string_s=PostingsFormat(name=LuceneVarGapFixedInterval), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=FST50, timestamp=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=cs_CZ, timezone=America/Yellowknife [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=1,free=356879184,total=512753664 [junit4] 2> NOTE: All tests run in this JVM: [DistributedTermsComponentTest, TestSortingResponseWriter, SpellCheckComponentTest, BasicFunctionalityTest, TestCodecSupport, TestDistributedGrouping, ZkStateReaderTest, TermsComponentTest, DistanceFunctionTest, TestRemoveLastDynamicCopyField, SSLMigrationTest, HdfsNNFailoverTest, AsyncCallRequestStatusResponseTest, TestSolrQueryParserResource, PreAnalyzedUpdateProcessorTest, SoftAutoCommitTest, TestJsonFacets, RulesTest, TestLeaderElectionZkExpiry, TestCopyFieldCollectionResource, VMParamsZkACLAndCredentialsProvidersTest, TestSolrCoreProperties, TestQueryTypes, FastVectorHighlighterTest, HighlighterTest, WordBreakSolrSpellCheckerTest, SolrCmdDistributorTest, DocValuesMissingTest, TestAuthenticationFramework, TestFieldCollectionResource, ShardRoutingTest, TestDownShardTolerantSearch, TestDistribDocBasedVersion, SortByFunctionTest, SchemaVersionSpecificBehaviorTest, TestLMJelinekMercerSimilarityFactory, TestShortCircuitedRequests, MoreLikeThisHandlerTest, BufferStoreTest, TestJettySolrRunner, StressHdfsTest, HdfsLockFactoryTest, UniqFieldsUpdateProcessorFactoryTest, RuleEngineTest, TestSQLHandler, ConvertedLegacyTest, AtomicUpdatesTest, TestUtils, MultiTermTest, TestSolrDeletionPolicy2, TestNRTOpen, TestLFUCache, TestFieldTypeResource, ConcurrentDeleteAndCreateCollectionTest, DistributedFacetPivotSmallTest, CoreAdminRequestStatusTest, TestHashPartitioner, SimplePostToolTest, DistribCursorPagingTest, FacetPivotSmallTest, TestCustomDocTransformer, TestTrie, HdfsRecoverLeaseTest, TestFreeTextSuggestions, TestScoreJoinQPScore, TestQueryUtils, ClusterStateUpdateTest, CustomCollectionTest, TestOverriddenPrefixQueryForCustomFieldType, TestReloadDeadlock, TestReqParamsAPI, CachingDirectoryFactoryTest, SpellCheckCollatorTest, TestQuerySenderNoQuery, TestQuerySenderListener, TestStressVersions, TestSchemaManager, TriLevelCompositeIdRoutingTest, TestRuleBasedAuthorizationPlugin, DistributedFacetPivotLongTailTest, DistributedQueueTest, MergeStrategyTest, DistribDocExpirationUpdateProcessorTest, SolrPluginUtilsTest, TestUpdate, TestRTGBase, TestSurroundQueryParser, CollectionsAPIAsyncDistributedZkTest, BasicDistributedZkTest, OpenCloseCoreStressTest, TestReplicationHandler, CloudExitableDirectoryReaderTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DistribJoinFromCollectionTest, LeaderInitiatedRecoveryOnCommitTest, MigrateRouteKeyTest, SolrCloudExampleTest, TestConfigSetsAPI, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestSolrCloudWithKerberosAlt, HdfsChaosMonkeySafeLeaderTest, HdfsThreadLeakTest, TestClusterStateMutator, ZkStateWriterTest, TestConfigOverlay, TestConfigSets, TestDynamicLoading, TestSolrConfigHandler, RequestLoggingTest, TestReplicationHandlerBackup, TestRestoreCore, TestSolrConfigHandlerConcurrent, DistributedFacetPivotSmallAdvancedTest, DistributedQueryComponentOptimizationTest, SuggestComponentTest, TestDistributedStatsComponentCardinality] [junit4] Completed [499/544] on J1 in 254.07s, 1 test, 1 error <<< FAILURES! [...truncated 159 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:514: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 544 suites, 2141 tests, 2 errors, 1 failure, 29 ignored (17 assumptions) Total time: 191 minutes 33 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 Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
