Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/789/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Error from server at http://127.0.0.1:55860/qm_qk/q: Could not load collection from ZK:halfcollectionblocker Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:55860/qm_qk/q: Could not load collection from ZK:halfcollectionblocker 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.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:302) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:419) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:169) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java: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) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=3438, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3438, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:53350/p/jb: Could not find collection : awholynewstresscollection_collection4_0 at __randomizedtesting.SeedInfo.seed([642EB9E4113325B5]: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) Build Log: [...truncated 10194 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_642EB9E4113325B5-001/init-core-data-001 [junit4] 2> 198267 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 198268 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /p/jb [junit4] 2> 199027 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-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> 199719 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 199905 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog [junit4] 2> 199915 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 199997 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 200052 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-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_50474_hdfs____.8e9i9s/webapp [junit4] 2> 200264 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 201232 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:50474 [junit4] 2> 202534 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 202541 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 202557 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-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_59159_datanode____.tzcnjc/webapp [junit4] 2> 202666 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 203176 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:59159 [junit4] 2> 203419 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 203421 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 203442 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-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_57051_datanode____shh3l/webapp [junit4] 2> 203580 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 204200 INFO (IPC Server handler 2 on 33296) [ ] BlockStateChange BLOCK* processReport: from storage DS-ca9fb14e-6eea-4d13-8a71-bc177dfe6168 node DatanodeRegistration(127.0.0.1, datanodeUuid=6808aacc-b3bd-4637-9774-914677faaf11, infoPort=59159, ipcPort=47368, storageInfo=lv=-56;cid=testClusterID;nsid=1055292187;c=0), blocks: 0, hasStaleStorages: true, processing time: 3 msecs [junit4] 2> 204202 INFO (IPC Server handler 2 on 33296) [ ] BlockStateChange BLOCK* processReport: from storage DS-ef70f2be-9c23-418d-af2d-f5c911e96654 node DatanodeRegistration(127.0.0.1, datanodeUuid=6808aacc-b3bd-4637-9774-914677faaf11, infoPort=59159, ipcPort=47368, storageInfo=lv=-56;cid=testClusterID;nsid=1055292187;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 204359 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:57051 [junit4] 2> 204565 INFO (IPC Server handler 2 on 33296) [ ] BlockStateChange BLOCK* processReport: from storage DS-c4f09b4f-b5a5-43f3-89b2-b0e3c148bf6e node DatanodeRegistration(127.0.0.1, datanodeUuid=f9afbb73-69ee-4fe9-b908-25ca5da0f0a6, infoPort=57051, ipcPort=59037, storageInfo=lv=-56;cid=testClusterID;nsid=1055292187;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 204566 INFO (IPC Server handler 2 on 33296) [ ] BlockStateChange BLOCK* processReport: from storage DS-6c4feef8-80ab-4188-b3b0-37637de6ef49 node DatanodeRegistration(127.0.0.1, datanodeUuid=f9afbb73-69ee-4fe9-b908-25ca5da0f0a6, infoPort=57051, ipcPort=59037, storageInfo=lv=-56;cid=testClusterID;nsid=1055292187;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 205250 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 205251 INFO (Thread-634) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 205251 INFO (Thread-634) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 205351 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.ZkTestServer start zk server on port:56550 [junit4] 2> 205351 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 205352 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 205357 INFO (zkCallback-175-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@542d75fa name:ZooKeeperConnection Watcher:127.0.0.1:56550 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 205357 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 205358 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 205358 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 205366 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 205370 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 205374 INFO (zkCallback-176-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@53a4fe05 name:ZooKeeperConnection Watcher:127.0.0.1:56550/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 205374 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 205374 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 205374 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 205377 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 205378 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 205379 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 205381 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205381 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 205383 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205384 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 205385 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205386 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 205387 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 205389 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205389 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 205391 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205391 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 205392 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205393 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 205394 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205394 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 205396 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205396 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 205399 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205399 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 205401 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205401 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 205410 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 205414 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 205427 INFO (zkCallback-177-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ed24cc4 name:ZooKeeperConnection Watcher:127.0.0.1:56550/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 205427 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 205427 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 205739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/control-001/cores/collection1 [junit4] 2> 205757 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 205759 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@37aa1953{/p/jb,null,AVAILABLE} [junit4] 2> 205840 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4070ccca{SSL-http/1.1}{127.0.0.1:53350} [junit4] 2> 205840 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.e.j.s.Server Started @209379ms [junit4] 2> 205841 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:33296/hdfs__localhost_33296__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_642EB9E4113325B5-001_tempDir-002_control_data, hostContext=/p/jb, hostPort=53350, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_642EB9E4113325B5-001/control-001/cores} [junit4] 2> 205841 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 205841 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/control-001/' [junit4] 2> 205858 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 205858 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 205861 INFO (zkCallback-178-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69d4e6e9 name:ZooKeeperConnection Watcher:127.0.0.1:56550/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 205861 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 205861 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 205863 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 205863 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/control-001/solr.xml [junit4] 2> 205871 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/control-001/cores [junit4] 2> 205871 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.CoreContainer New CoreContainer 465967252 [junit4] 2> 205871 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/control-001/] [junit4] 2> 205871 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/control-001/lib [junit4] 2> 205871 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/control-001/lib). [junit4] 2> 205880 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 205889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 205891 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 205891 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 205891 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56550/solr [junit4] 2> 205891 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 205891 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 205892 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 205897 INFO (zkCallback-180-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@61ea7773 name:ZooKeeperConnection Watcher:127.0.0.1:56550 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 205897 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 205898 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 205902 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 205912 INFO (zkCallback-181-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1fa7c921 name:ZooKeeperConnection Watcher:127.0.0.1:56550/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 205912 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 205914 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 205918 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 205921 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 205923 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 205924 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 205926 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 205928 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 205929 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 205930 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 205931 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53350_p%2Fjb [junit4] 2> 205931 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:53350_p%2Fjb [junit4] 2> 205933 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 205934 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 205935 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 205935 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000 [junit4] 2> 205936 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53350_p%2Fjb [junit4] 2> 205936 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 205937 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.Overseer Overseer (id=94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) starting [junit4] 2> 205939 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 205943 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 205944 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 205945 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 205950 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 205951 INFO (OverseerCollectionConfigSetProcessor-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 205990 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 205990 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 205991 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] 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_642EB9E4113325B5-001/control-001/cores [junit4] 2> 205992 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] 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_642EB9E4113325B5-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_642EB9E4113325B5-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 205992 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] 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_642EB9E4113325B5-001/control-001/cores/collection1/ [junit4] 2> 205993 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 205996 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 205996 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 205996 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 205996 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 205998 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:53350/p/jb";, [junit4] 2> "node_name":"127.0.0.1:53350_p%2Fjb", [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> 205998 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 206000 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:53350/p/jb";, [junit4] 2> "node_name":"127.0.0.1:53350_p%2Fjb", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 206000 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 206000 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 206002 INFO (zkCallback-181-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] 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> 206006 INFO (zkCallback-181-thread-2-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] 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> 207000 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 207000 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 207001 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 207001 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 207002 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 207002 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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_642EB9E4113325B5-001/control-001/cores/collection1/' [junit4] 2> 207018 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 207024 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 207028 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 207042 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 207044 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 207058 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 207187 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 207196 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 207197 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 207208 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 207211 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 207220 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 207221 WARN (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 207221 WARN (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 207221 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 207222 WARN (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 207222 WARN (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 207223 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 207223 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 207223 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 207237 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:33296/solr_hdfs_home [junit4] 2> 207237 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 207238 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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_642EB9E4113325B5-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 207238 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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@fc94f15 [junit4] 2> 207238 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:33296/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 207344 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:33296/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 207344 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:33296/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 207350 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:33296/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 207387 WARN (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:33296/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 207387 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:33296/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 207443 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:33296/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 207443 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:33296/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 207449 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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@fd77165 [junit4] 2> 207858 INFO (IPC Server handler 3 on 33296) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60749 is added to blk_1073741825_1001{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-ca9fb14e-6eea-4d13-8a71-bc177dfe6168:NORMAL:127.0.0.1:42822|RBW], ReplicaUnderConstruction[[DISK]DS-c4f09b4f-b5a5-43f3-89b2-b0e3c148bf6e:NORMAL:127.0.0.1:60749|RBW]]} size 71 [junit4] 2> 207860 INFO (IPC Server handler 2 on 33296) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42822 is added to blk_1073741825_1001 size 71 [junit4] 2> 208294 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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@b6078446 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a5d2867; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 208294 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 208309 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 208311 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 208311 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 208311 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 208312 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 208312 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208312 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208312 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208312 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208320 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 208322 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 208323 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 208324 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 208328 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53350_p%2Fjb 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:33296/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 208341 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208341 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208379 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208380 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208420 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 208424 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 208436 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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=38, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 208610 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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@b6078446 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a5d2867; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 208610 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 208611 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@c3088d2[collection1] main [junit4] 2> 208611 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208612 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208612 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208612 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 208612 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 208613 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208613 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208613 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208613 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 208614 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 208616 INFO (searcherExecutor-590-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c3088d2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 208628 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208628 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208628 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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> 208628 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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 1511741177175474176 [junit4] 2> 208628 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb 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 1511741177175474176 [junit4] 2> 208628 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 208629 INFO (coreLoadExecutor-589-thread-1-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 208638 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:53350/p/jb collection:control_collection shard:shard1 [junit4] 2> 208650 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb 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> 208655 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb 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/94483823352217605-core_node1-n_0000000000 [junit4] 2> 208655 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 208657 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 208657 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb 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> 208657 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] 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> 208657 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:53350/p/jb/collection1/ [junit4] 2> 208658 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 208658 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:53350/p/jb/collection1/ has no replicas [junit4] 2> 208658 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:53350/p/jb/collection1/ shard1 [junit4] 2> 208658 INFO (zkCallback-181-thread-2-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] 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> 208659 INFO (zkCallback-181-thread-3-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] 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> 208662 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 208663 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 208666 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"https://127.0.0.1:53350/p/jb";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 208774 INFO (zkCallback-181-thread-2-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] 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> 208774 INFO (zkCallback-181-thread-3-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] 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> 208817 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:53350/p/jb/collection1/ and leader is https://127.0.0.1:53350/p/jb/collection1/ [junit4] 2> 208817 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:53350/p/jb [junit4] 2> 208817 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 208817 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 208817 INFO (coreZkRegister-583-thread-1-processing-n:127.0.0.1:53350_p%2Fjb x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53350_p%2Fjb 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> 208819 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] 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":"https://127.0.0.1:53350/p/jb";, [junit4] 2> "node_name":"127.0.0.1:53350_p%2Fjb", [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> 208820 INFO (OverseerStateUpdate-94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000) [n:127.0.0.1:53350_p%2Fjb ] 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":"https://127.0.0.1:53350/p/jb";, [junit4] 2> "node_name":"127.0.0.1:53350_p%2Fjb", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 208926 INFO (zkCallback-181-thread-3-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] 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> 208926 INFO (zkCallback-181-thread-2-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] 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> 209011 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 209038 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 209062 INFO (zkCallback-183-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a0d51ba name:ZooKeeperConnection Watcher:127.0.0.1:56550/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 209062 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 209062 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 209063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 209065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 210170 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/shard-1-001/cores/collection1 [junit4] 2> 210171 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/shard-1-001 [junit4] 2> 210183 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 210257 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f8e82a4{/p/jb,null,AVAILABLE} [junit4] 2> 210272 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3c55987f{SSL-http/1.1}{127.0.0.1:50695} [junit4] 2> 210272 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.e.j.s.Server Started @213811ms [junit4] 2> 210272 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:33296/hdfs__localhost_33296__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_642EB9E4113325B5-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/p/jb, hostPort=50695, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_642EB9E4113325B5-001/shard-1-001/cores} [junit4] 2> 210273 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 210273 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/shard-1-001/' [junit4] 2> 210390 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 210439 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 210454 INFO (zkCallback-184-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74f52d42 name:ZooKeeperConnection Watcher:127.0.0.1:56550/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 210454 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 210455 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 210462 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 210463 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/shard-1-001/solr.xml [junit4] 2> 210470 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/shard-1-001/cores [junit4] 2> 210471 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.CoreContainer New CoreContainer 767297369 [junit4] 2> 210471 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/shard-1-001/] [junit4] 2> 210471 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/shard-1-001/lib [junit4] 2> 210471 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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_642EB9E4113325B5-001/shard-1-001/lib). [junit4] 2> 210521 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] 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> 210553 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 210557 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 210557 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 210557 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56550/solr [junit4] 2> 210557 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 210558 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 210567 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 210583 INFO (zkCallback-186-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@612ef8c4 name:ZooKeeperConnection Watcher:127.0.0.1:56550 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 210587 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 210587 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 210602 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 210630 INFO (zkCallback-187-thread-1-processing-n:127.0.0.1:50695_p%2Fjb) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e3ebb26 name:ZooKeeperConnection Watcher:127.0.0.1:56550/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 210630 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 210637 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 211645 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50695_p%2Fjb [junit4] 2> 211645 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:50695_p%2Fjb [junit4] 2> 211647 INFO (zkCallback-181-thread-2-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 211647 INFO (zkCallback-187-thread-1-processing-n:127.0.0.1:50695_p%2Fjb) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 211647 INFO (zkCallback-181-thread-3-processing-n:127.0.0.1:53350_p%2Fjb) [n:127.0.0.1:53350_p%2Fjb ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 211647 INFO (zkCallback-183-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 211652 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 211653 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94483823352217609-127.0.0.1:50695_p%2Fjb-n_0000000001 [junit4] 2> 211653 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94483823352217605-127.0.0.1:53350_p%2Fjb-n_0000000000 to know if I could be the leader [junit4] 2> 211696 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 211696 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 211697 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] 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_642EB9E4113325B5-001/shard-1-001/cores [junit4] 2> 211700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] 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_642EB9E4113325B5-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_642EB9E4113325B5-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 211700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] 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_642EB9E4113325B5-001/shard-1-001/cores/collection1/ [junit4] 2> 211700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[642EB9E4113325B5]) [n:127.0.0.1:50695_p%2Fjb ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 211720 INFO (coreLoadExecutor-600-thread-1-processing-n:127.0.0.1:50695_p%2Fjb) [n:127.0.0.1:50695_p%2Fjb c:collection1 x:collection1] o.a.s.c.ZkController [...truncated too long message...] 6984, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:48851/qm_qk/q, http://127.0.0.1:55860/qm_qk/q, http://127.0.0.1:35141/qm_qk/q, http://127.0.0.1:46747/qm_qk/q, http://127.0.0.1:45754/qm_qk/q] [junit4] > at __randomizedtesting.SeedInfo.seed([642EB9E4113325B5]:0) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:898) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:48851/qm_qk/q, http://127.0.0.1:55860/qm_qk/q, http://127.0.0.1:35141/qm_qk/q, http://127.0.0.1:46747/qm_qk/q, http://127.0.0.1:45754/qm_qk/q] [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:46747/qm_qk/q [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:572) [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.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > ... 5 more [junit4] > Caused by: java.net.SocketTimeoutException: Read timed out [junit4] > at java.net.SocketInputStream.socketRead0(Native Method) [junit4] > at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:170) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:141) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) [junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) [junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:465) [junit4] > ... 9 more [junit4] 2> 1917093 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[642EB9E4113325B5]-worker) [n:127.0.0.1:45754_qm_qk%2Fq c:nodes_used_collection s:shard1 r:core_node4 x:nodes_used_collection_shard1_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_642EB9E4113325B5-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l_dv=PostingsFormat(name=LuceneVarGapFixedInterval), _version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=Lucene50(blocksize=128), a_t=Lucene50(blocksize=128), intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_i1=Lucene50(blocksize=128), id=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_i_dv=Lucene50(blocksize=128), text=PostingsFormat(name=Memory doPackFST= false), range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=hi_IN, timezone=Asia/Ujung_Pandang [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=1,free=57578256,total=478674944 [junit4] 2> NOTE: All tests run in this JVM: [ClusterStateUpdateTest, TestBlendedInfixSuggestions, URLClassifyProcessorTest, TestLeaderElectionZkExpiry, TestDistribDocBasedVersion, TestConfigOverlay, TestRemoveLastDynamicCopyField, ShardRoutingCustomTest, PreAnalyzedFieldTest, TestCustomDocTransformer, BJQParserTest, TestStressVersions, TestRTGBase, CSVRequestHandlerTest, DistributedFacetPivotWhiteBoxTest, TestCoreContainer, ChaosMonkeySafeLeaderTest, TestSchemaVersionResource, TestWordDelimiterFilterFactory, DocumentBuilderTest, HighlighterTest, SolrCmdDistributorTest, SolrCloudExampleTest, HdfsChaosMonkeySafeLeaderTest, TestHashPartitioner, SolrXmlInZkTest, TestAuthenticationFramework, TestCloudPivotFacet, DistributedQueueTest, NumericFieldsTest, TestShortCircuitedRequests, TestFiltering, TestFastOutputStream, WordBreakSolrSpellCheckerTest, HLLSerializationTest, TestJsonRequest, TestSchemaManager, TestDistributedGrouping, DistanceUnitsTest, UpdateRequestProcessorFactoryTest, DocExpirationUpdateProcessorFactoryTest, TestMaxScoreQueryParser, HdfsRecoveryZkTest, TestDynamicLoading, TestJsonFacets, DistributedFacetPivotLongTailTest, TestUpdate, TestDownShardTolerantSearch, TestCustomSort, SoftAutoCommitTest, TestSolrQueryParserResource, SliceStateTest, VMParamsZkACLAndCredentialsProvidersTest, SpellCheckCollatorTest, MoreLikeThisHandlerTest, HdfsRecoverLeaseTest, TestFieldCollectionResource, TestSortByMinMaxFunction, CircularListTest, TestCodecSupport, DOMUtilTest, SSLMigrationTest, TestTrie, HdfsNNFailoverTest, AsyncCallRequestStatusResponseTest, TestManagedSchema, SchemaVersionSpecificBehaviorTest, SpatialFilterTest, TestSortingResponseWriter, TestSolrDeletionPolicy2, TestSurroundQueryParser, DirectSolrSpellCheckerTest, MergeStrategyTest, SortByFunctionTest, PreAnalyzedUpdateProcessorTest, TestEmbeddedSolrServerConstructors, FastVectorHighlighterTest, TestCrossCoreJoin, DistributedExpandComponentTest, BitVectorTest, CdcrRequestHandlerTest, TestLMJelinekMercerSimilarityFactory, AddSchemaFieldsUpdateProcessorFactoryTest, RuleEngineTest, AnalyticsQueryTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest] [junit4] Completed [195/544] on J1 in 736.43s, 1 test, 1 error <<< FAILURES! [...truncated 1135 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, 29 ignored (17 assumptions) Total time: 187 minutes 25 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
