Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/788/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=38041, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=38041, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:49925/_vi/hf: Could not find collection : awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([DE08134BD698D156]: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 11443 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/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/init-core-data-001 [junit4] 2> 4313520 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 4313520 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_vi/hf [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 4313619 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 4313636 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4313641 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4313655 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-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_43364_hdfs____.z79z44/webapp [junit4] 2> 4313909 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4314707 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:43364 [junit4] 2> 4315121 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4315122 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4315134 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-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_52202_datanode____.c8j63m/webapp [junit4] 2> 4315331 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4315815 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:52202 [junit4] 2> 4316026 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4316028 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4316049 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-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_41709_datanode____.6if06u/webapp [junit4] 2> 4316168 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4316434 INFO (IPC Server handler 4 on 47898) [ ] BlockStateChange BLOCK* processReport: from storage DS-e2fef903-376f-4977-83bc-3b460a19f524 node DatanodeRegistration(127.0.0.1, datanodeUuid=20dd86b3-df9c-41f0-baa7-9f8d65afd619, infoPort=52202, ipcPort=40266, storageInfo=lv=-56;cid=testClusterID;nsid=569659897;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 4316435 INFO (IPC Server handler 4 on 47898) [ ] BlockStateChange BLOCK* processReport: from storage DS-9ef25a07-0523-41b1-b7b3-04b22a96a54a node DatanodeRegistration(127.0.0.1, datanodeUuid=20dd86b3-df9c-41f0-baa7-9f8d65afd619, infoPort=52202, ipcPort=40266, storageInfo=lv=-56;cid=testClusterID;nsid=569659897;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 4316687 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DE08134BD698D156]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:41709 [junit4] 2> 4317094 INFO (IPC Server handler 8 on 47898) [ ] BlockStateChange BLOCK* processReport: from storage DS-e478a387-992d-4fc5-a125-624d637d52fa node DatanodeRegistration(127.0.0.1, datanodeUuid=c7530253-8e0f-4b7d-8b5f-9809e59117bd, infoPort=41709, ipcPort=42014, storageInfo=lv=-56;cid=testClusterID;nsid=569659897;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 4317096 INFO (IPC Server handler 8 on 47898) [ ] BlockStateChange BLOCK* processReport: from storage DS-7fa373e8-aca3-4428-96be-af417bf23a28 node DatanodeRegistration(127.0.0.1, datanodeUuid=c7530253-8e0f-4b7d-8b5f-9809e59117bd, infoPort=41709, ipcPort=42014, storageInfo=lv=-56;cid=testClusterID;nsid=569659897;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 4317285 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 4317290 INFO (Thread-21517) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4317290 INFO (Thread-21517) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 4317390 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.ZkTestServer start zk server on port:51500 [junit4] 2> 4317390 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4317407 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4317434 INFO (zkCallback-3685-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@481812c0 name:ZooKeeperConnection Watcher:127.0.0.1:51500 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4317435 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4317435 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4317435 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 4317438 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4317450 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4317466 INFO (zkCallback-3686-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2595c9c1 name:ZooKeeperConnection Watcher:127.0.0.1:51500/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4317466 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4317467 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4317467 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 4317469 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 4317470 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 4317471 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 4317472 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317472 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4317474 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317474 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 4317476 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317476 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4317477 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317478 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 4317479 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317479 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 4317480 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317481 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 4317482 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317482 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4317483 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317484 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4317485 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317485 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4317486 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317486 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4317488 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4317488 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 4317490 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4317514 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4317530 INFO (zkCallback-3687-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58ff8eec name:ZooKeeperConnection Watcher:127.0.0.1:51500/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4317530 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4317531 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4317532 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4317546 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4317557 INFO (zkCallback-3688-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1037d2c9 name:ZooKeeperConnection Watcher:127.0.0.1:51500 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4317557 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4317558 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4317558 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 4317560 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4317578 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4317606 INFO (zkCallback-3689-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@787f03b8 name:ZooKeeperConnection Watcher:127.0.0.1:51500/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4317606 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4317606 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4317607 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 4317607 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 4317609 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 4317609 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 4317611 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 4317611 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 4317613 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4317613 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4317614 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 4317614 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 4317616 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 4317616 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 4317617 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 4317617 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 4317619 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 4317619 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 4317620 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 4317620 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 4317622 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 4317622 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 4317623 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 4317624 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 4317625 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 4317625 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 4318024 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores/collection1 [junit4] 2> 4318028 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 4318031 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@280b6d0c{/_vi/hf,null,AVAILABLE} [junit4] 2> 4318035 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3286d8ae{SSL-http/1.1}{127.0.0.1:39716} [junit4] 2> 4318036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.e.j.s.Server Started @4322406ms [junit4] 2> 4318036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:47898/hdfs__localhost_47898__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001_tempDir-002_control_data, hostContext=/_vi/hf, hostPort=39716, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores} [junit4] 2> 4318036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 4318036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/' [junit4] 2> 4318052 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4318054 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4318058 INFO (zkCallback-3690-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@63c7ac5a name:ZooKeeperConnection Watcher:127.0.0.1:51500/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4318058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4318058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4318060 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4318060 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/solr.xml [junit4] 2> 4318065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores [junit4] 2> 4318065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.CoreContainer New CoreContainer 77122461 [junit4] 2> 4318065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/] [junit4] 2> 4318065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/lib [junit4] 2> 4318066 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/lib). [junit4] 2> 4318073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4318075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4318077 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4318077 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4318077 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51500/solr [junit4] 2> 4318077 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4318077 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4318078 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4318080 INFO (zkCallback-3692-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c43328f name:ZooKeeperConnection Watcher:127.0.0.1:51500 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4318080 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4318080 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4318083 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4318083 INFO (zkCallback-3693-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@759fcccf name:ZooKeeperConnection Watcher:127.0.0.1:51500/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4318083 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4318084 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 4318086 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 4318088 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 4318090 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 4318091 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 4318093 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 4318094 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 4318095 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 4318096 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 4318097 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39716__vi%2Fhf [junit4] 2> 4318098 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:39716__vi%2Fhf [junit4] 2> 4318099 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 4318100 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 4318101 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4318101 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000 [junit4] 2> 4318102 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39716__vi%2Fhf [junit4] 2> 4318102 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 4318103 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.Overseer Overseer (id=94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) starting [junit4] 2> 4318104 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 4318109 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4318110 INFO (OverseerCollectionConfigSetProcessor-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 4318110 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 4318111 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4318123 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4318148 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 4318148 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 4318149 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores [junit4] 2> 4318150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 4318150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores/collection1/ [junit4] 2> 4318150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 4318155 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 4318155 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 4318158 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 4318158 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 4318159 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] 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:39716/_vi/hf";, [junit4] 2> "node_name":"127.0.0.1:39716__vi%2Fhf", [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> 4318160 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 4318160 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] 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:39716/_vi/hf";, [junit4] 2> "node_name":"127.0.0.1:39716__vi%2Fhf", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4318160 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 4318161 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 4318162 INFO (zkCallback-3693-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4318170 INFO (zkCallback-3693-thread-2-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4319160 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 4319160 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 4319161 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 4319161 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 4319162 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4319162 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores/collection1/' [junit4] 2> 4319186 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 4319190 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 4319194 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4319254 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 4319257 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4319262 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4319342 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4319359 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 4319360 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 4319389 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 4319391 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 4319393 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4319394 WARN (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 4319395 WARN (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 4319395 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4319396 WARN (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 4319396 WARN (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 4319402 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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/J0 [junit4] 2> 4319402 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4319402 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 4319404 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:47898/solr_hdfs_home [junit4] 2> 4319404 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 4319404 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 4319404 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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@262b74fc [junit4] 2> 4319405 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:47898/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 4319479 WARN (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 4324482 WARN (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 4329486 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:47898/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 4329486 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:47898/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 4329490 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:47898/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 4329511 WARN (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:47898/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 4329511 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:47898/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 4329541 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:47898/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 4329541 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:47898/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 4329545 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=670581922, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 4329637 INFO (IPC Server handler 7 on 47898) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:55747 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9ef25a07-0523-41b1-b7b3-04b22a96a54a:NORMAL:127.0.0.1:51178|RBW], ReplicaUnderConstruction[[DISK]DS-7fa373e8-aca3-4428-96be-af417bf23a28:NORMAL:127.0.0.1:55747|RBW]]} size 0 [junit4] 2> 4329640 INFO (IPC Server handler 5 on 47898) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:51178 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-7fa373e8-aca3-4428-96be-af417bf23a28:NORMAL:127.0.0.1:55747|RBW], ReplicaUnderConstruction[[DISK]DS-e2fef903-376f-4977-83bc-3b460a19f524:NORMAL:127.0.0.1:51178|FINALIZED]]} size 0 [junit4] 2> 4329645 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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@b7b9c568 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c1c1c42; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 4329646 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4329648 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:39716__vi%2Fhf 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:47898/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 4329650 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4329651 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 4329651 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4329651 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4329651 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4329651 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329652 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329652 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329652 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329654 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4329655 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4329656 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4329657 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4329660 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329661 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329662 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329662 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329675 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 4329675 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 4329676 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=6.833984375, floorSegmentMB=1.2783203125, forceMergeDeletesPctAllowed=20.49523558952309, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.875724482398419 [junit4] 2> 4329687 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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@b7b9c568 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c1c1c42; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 4329687 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4329688 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6fd3fc9d[collection1] main [junit4] 2> 4329688 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329690 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329690 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329690 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4329691 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 4329691 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329692 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329692 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329692 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 4329692 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 4329693 INFO (searcherExecutor-9027-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6fd3fc9d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4329697 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329697 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329697 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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> 4329698 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf 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 1511653786622361600 [junit4] 2> 4329698 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1511653786622361600 [junit4] 2> 4329698 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 4329699 INFO (coreLoadExecutor-9026-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 4329700 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf 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:39716/_vi/hf collection:control_collection shard:shard1 [junit4] 2> 4329701 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf 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> 4329705 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf 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/94478360851120135-core_node1-n_0000000000 [junit4] 2> 4329705 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 4329707 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4329707 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf 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> 4329707 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:39716/_vi/hf/collection1/ [junit4] 2> 4329707 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4329707 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 4329708 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:39716/_vi/hf/collection1/ has no replicas [junit4] 2> 4329708 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf 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:39716/_vi/hf/collection1/ shard1 [junit4] 2> 4329708 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4329708 INFO (zkCallback-3693-thread-3-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4329708 INFO (zkCallback-3693-thread-2-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4329711 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 4329713 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] 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:39716/_vi/hf";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 4329816 INFO (zkCallback-3693-thread-3-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4329816 INFO (zkCallback-3693-thread-2-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4329866 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:39716/_vi/hf/collection1/ and leader is https://127.0.0.1:39716/_vi/hf/collection1/ [junit4] 2> 4329866 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf 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:39716/_vi/hf [junit4] 2> 4329866 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 4329866 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 4329866 INFO (coreZkRegister-9020-thread-1-processing-n:127.0.0.1:39716__vi%2Fhf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39716__vi%2Fhf 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> 4329868 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] 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:39716/_vi/hf";, [junit4] 2> "node_name":"127.0.0.1:39716__vi%2Fhf", [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> 4329869 INFO (OverseerStateUpdate-94478360851120135-127.0.0.1:39716__vi%2Fhf-n_0000000000) [n:127.0.0.1:39716__vi%2Fhf ] 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:39716/_vi/hf";, [junit4] 2> "node_name":"127.0.0.1:39716__vi%2Fhf", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4329971 INFO (zkCallback-3693-thread-2-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4329971 INFO (zkCallback-3693-thread-3-processing-n:127.0.0.1:39716__vi%2Fhf) [n:127.0.0.1:39716__vi%2Fhf ] 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> 4330165 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4330180 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4330192 INFO (zkCallback-3695-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@148e6554 name:ZooKeeperConnection Watcher:127.0.0.1:51500/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4330192 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4330192 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4330193 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4330194 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4330997 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001/cores/collection1 [junit4] 2> 4330998 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001 [junit4] 2> 4331002 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 4331038 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@75b2e149{/_vi/hf,null,AVAILABLE} [junit4] 2> 4331050 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2d0384b9{SSL-http/1.1}{127.0.0.1:58259} [junit4] 2> 4331050 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.e.j.s.Server Started @4335420ms [junit4] 2> 4331050 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:47898/hdfs__localhost_47898__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/_vi/hf, hostPort=58259, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001/cores} [junit4] 2> 4331052 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 4331052 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001/' [junit4] 2> 4331094 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4331106 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4331113 INFO (zkCallback-3696-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29b59e86 name:ZooKeeperConnection Watcher:127.0.0.1:51500/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4331113 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4331114 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4331115 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4331115 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001/solr.xml [junit4] 2> 4331119 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001/cores [junit4] 2> 4331120 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.CoreContainer New CoreContainer 295635512 [junit4] 2> 4331120 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001/] [junit4] 2> 4331120 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001/lib [junit4] 2> 4331120 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_DE08134BD698D156-001/shard-1-001/lib). [junit4] 2> 4331127 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] 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> 4331150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4331152 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4331153 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4331153 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51500/solr [junit4] 2> 4331153 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4331153 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4331174 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[DE08134BD698D156]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4331194 INFO (zkCallback-3698-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org. [...truncated too long message...] ceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/commons-collections-3.2.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/presto-parser-0.108.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/build/analysis/icu/lucene-analyzers-icu-6.0.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/contrib/analysis-extras/lib/icu4j-54.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.1.13.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20150907_061737_459.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20150907_061737_459.suites [junit4] ERROR: JVM J1 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1504) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:133) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:964) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:961) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] at java.lang.Thread.run(Thread.java:745) 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: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. Total time: 287 minutes 49 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
