Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/12/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=72615, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=72615, 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:50067/i/ta: Could not find collection : awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([C71A858F30673F1A]: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:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) Build Log: [...truncated 10664 lines...] [junit4] JVM J2: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160113_064507_573.sysout [junit4] >>> JVM J2: stdout (verbatim) ---- [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/heapdumps/java_pid8513.hprof ... [junit4] Heap dump file created [534234112 bytes in 12.851 secs] [junit4] <<< JVM J2: EOF ---- [...truncated 1015 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/init-core-data-001 [junit4] 2> 3850826 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 3850826 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /i/ta [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 3850868 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 3850877 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3850880 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3850896 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-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_47904_hdfs____rmbbr8/webapp [junit4] 2> 3851030 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3851285 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47904 [junit4] 2> 3851359 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3851361 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3851374 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-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_58272_datanode____kmyoz5/webapp [junit4] 2> 3851494 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3851751 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58272 [junit4] 2> 3851815 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3851818 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3851840 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-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_46575_datanode____4cntko/webapp [junit4] 2> 3851925 INFO (IPC Server handler 3 on 56525) [ ] BlockStateChange BLOCK* processReport: from storage DS-fbe74c9f-4c83-416f-8a0d-8839fa33998c node DatanodeRegistration(127.0.0.1, datanodeUuid=6c5aff65-6fc9-439d-8b8d-06016e5a30b9, infoPort=58272, ipcPort=38089, storageInfo=lv=-56;cid=testClusterID;nsid=133783090;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 3851926 INFO (IPC Server handler 3 on 56525) [ ] BlockStateChange BLOCK* processReport: from storage DS-91c955f2-2be8-4b0e-ab9f-0e6af0b5525b node DatanodeRegistration(127.0.0.1, datanodeUuid=6c5aff65-6fc9-439d-8b8d-06016e5a30b9, infoPort=58272, ipcPort=38089, storageInfo=lv=-56;cid=testClusterID;nsid=133783090;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 3851997 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3852234 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C71A858F30673F1A]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46575 [junit4] 2> 3852331 INFO (IPC Server handler 8 on 56525) [ ] BlockStateChange BLOCK* processReport: from storage DS-e3aba85f-ee1b-429f-97e7-6c59a8b7de03 node DatanodeRegistration(127.0.0.1, datanodeUuid=e131a329-1630-4d05-b79b-7f81112dea41, infoPort=46575, ipcPort=37671, storageInfo=lv=-56;cid=testClusterID;nsid=133783090;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 3852332 INFO (IPC Server handler 8 on 56525) [ ] BlockStateChange BLOCK* processReport: from storage DS-124f4925-fbdb-4910-ac5d-66d71d4c674d node DatanodeRegistration(127.0.0.1, datanodeUuid=e131a329-1630-4d05-b79b-7f81112dea41, infoPort=46575, ipcPort=37671, storageInfo=lv=-56;cid=testClusterID;nsid=133783090;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 3852401 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3852401 INFO (Thread-59577) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3852402 INFO (Thread-59577) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 3852502 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.ZkTestServer start zk server on port:52626 [junit4] 2> 3852502 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3852503 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3852505 INFO (zkCallback-2462-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ffd757f name:ZooKeeperConnection Watcher:127.0.0.1:52626 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852505 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3852506 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3852506 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 3852508 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3852509 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3852510 INFO (zkCallback-2463-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3fb52d8f name:ZooKeeperConnection Watcher:127.0.0.1:52626/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852510 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3852510 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3852511 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 3852512 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 3852513 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 3852515 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 3852516 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3852516 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3852520 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 3852520 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 3852522 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3852522 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3852523 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3852524 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 3852525 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3852525 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 3852527 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 3852527 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 3852528 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3852529 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3852530 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3852530 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3852532 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3852532 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3852533 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3852534 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3852535 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3852535 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 3852538 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3852538 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3852540 INFO (zkCallback-2464-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c2c54cd name:ZooKeeperConnection Watcher:127.0.0.1:52626/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852540 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3852540 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3852542 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3852542 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3852544 INFO (zkCallback-2465-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cb5a4f name:ZooKeeperConnection Watcher:127.0.0.1:52626 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852544 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3852544 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3852544 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 3852546 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3852546 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3852547 INFO (zkCallback-2466-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@40eaebda name:ZooKeeperConnection Watcher:127.0.0.1:52626/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852548 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3852548 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3852548 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 3852548 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 3852550 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 3852551 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 3852552 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 3852553 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 3852554 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3852555 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3852556 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 3852556 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 3852558 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 3852558 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 3852559 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 3852560 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 3852561 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 3852562 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 3852563 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 3852563 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 3852565 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 3852565 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 3852566 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 3852567 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 3852568 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 3852568 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 3852658 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores/collection1 [junit4] 2> 3852659 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 3852661 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@72159087{/i/ta,null,AVAILABLE} [junit4] 2> 3852665 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.e.j.s.ServerConnector Started ServerConnector@65c3c994{SSL-http/1.1}{127.0.0.1:50067} [junit4] 2> 3852666 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.e.j.s.Server Started @3856044ms [junit4] 2> 3852666 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=50067, hostContext=/i/ta, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores, solr.data.dir=hdfs://localhost:56525/hdfs__localhost_56525__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001_tempDir-002_control_data} [junit4] 2> 3852666 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 3852666 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/' [junit4] 2> 3852682 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3852683 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3852684 INFO (zkCallback-2467-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f59fb10 name:ZooKeeperConnection Watcher:127.0.0.1:52626/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852684 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3852685 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3852686 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 3852686 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/solr.xml [junit4] 2> 3852700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores [junit4] 2> 3852700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.CoreContainer New CoreContainer 2086665931 [junit4] 2> 3852700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/] [junit4] 2> 3852700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/lib [junit4] 2> 3852700 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] 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-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/lib). [junit4] 2> 3852708 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] 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> 3852711 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3852712 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3852712 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3852712 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52626/solr [junit4] 2> 3852713 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3852713 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3852713 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3852715 INFO (zkCallback-2469-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@71d57bdf name:ZooKeeperConnection Watcher:127.0.0.1:52626 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852715 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3852715 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3852717 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3852718 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@61729b69 name:ZooKeeperConnection Watcher:127.0.0.1:52626/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3852718 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3852719 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 3852721 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 3852723 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 3852724 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 3852726 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 3852728 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 3852730 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 3852731 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 3852732 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 3852733 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50067_i%2Fta [junit4] 2> 3852734 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:50067_i%2Fta [junit4] 2> 3852735 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 3852736 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 3852738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3852738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000 [junit4] 2> 3852739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50067_i%2Fta [junit4] 2> 3852739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 3852740 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.Overseer Overseer (id=95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) starting [junit4] 2> 3852742 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 3852747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3852748 INFO (OverseerCollectionProcessor-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 3852748 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3852748 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 3852772 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 3852772 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 3852773 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores [junit4] 2> 3852774 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.SolrCore Created CoreDescriptor: {coreNodeName=, loadOnStartup=true, shard=, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores/collection1/, name=collection1, dataDir=data/, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores/collection1, schema=schema.xml, transient=false, config=solrconfig.xml, collection=control_collection} [junit4] 2> 3853383 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores/collection1/ [junit4] 2> 3853384 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 3853388 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 3853389 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 3853389 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0 [junit4] 2> 3853389 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [n:127.0.0.1:50067_i%2Fta ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 3853393 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3853393 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 3853394 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:50067_i%2Fta", [junit4] 2> "shard":null, [junit4] 2> "base_url":"https://127.0.0.1:50067/i/ta";, [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 3853395 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:50067_i%2Fta", [junit4] 2> "shard":null, [junit4] 2> "base_url":"https://127.0.0.1:50067/i/ta";, [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3853395 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 3853395 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 3853396 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] 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> 3853399 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 3854394 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 3854395 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 3854395 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 3854396 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 3854397 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3854397 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores/collection1/' [junit4] 2> 3854414 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 3854421 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 3854433 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2 [junit4] 2> 3854461 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 3854462 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3854468 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 3854697 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3854705 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 3854707 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 3854715 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3854718 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3854722 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3854724 WARN (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 3854724 WARN (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 3854724 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3854726 WARN (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 3854726 WARN (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 3854728 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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-5.3/solr/build/solr-core/test/J0 [junit4] 2> 3854728 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3854729 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 3854729 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:56525/solr_hdfs_home [junit4] 2> 3854729 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 3854729 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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-5.3/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 3854730 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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@af08a49 [junit4] 2> 3854730 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:56525/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 3854791 WARN (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859796 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:56525/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 3859797 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:56525/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 3859797 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:56525/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 3859807 WARN (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:56525/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 3859808 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:56525/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 3859821 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:56525/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 3859821 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:56525/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 3859822 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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=34, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=3.2529296875, floorSegmentMB=1.1748046875, forceMergeDeletesPctAllowed=0.4722275096616646, segmentsPerTier=43.0, maxCFSSegmentSizeMB=2.119140625, noCFSRatio=0.40545044782901063 [junit4] 2> 3859857 INFO (IPC Server handler 5 on 56525) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40748 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fbe74c9f-4c83-416f-8a0d-8839fa33998c:NORMAL:127.0.0.1:50444|RBW], ReplicaUnderConstruction[[DISK]DS-e3aba85f-ee1b-429f-97e7-6c59a8b7de03:NORMAL:127.0.0.1:40748|RBW]]} size 0 [junit4] 2> 3859859 INFO (IPC Server handler 7 on 56525) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:50444 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-fbe74c9f-4c83-416f-8a0d-8839fa33998c:NORMAL:127.0.0.1:50444|RBW], ReplicaUnderConstruction[[DISK]DS-e3aba85f-ee1b-429f-97e7-6c59a8b7de03:NORMAL:127.0.0.1:40748|RBW]]} size 0 [junit4] 2> 3859865 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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@b8ba552f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11c8a4b7; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 3859865 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 3859867 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:50067_i%2Fta 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:56525/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 3859872 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3859873 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 3859873 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3859873 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3859873 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3859874 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859875 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859875 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859875 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859879 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3859880 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3859883 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3859884 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3859887 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/luke,/get,/admin/file,/update/csv,/admin/ping,/admin/segments,/admin/plugins,/admin/system,/update,standard,/replication,/update/json/docs,/config,/schema,/admin/mbeans,/admin/properties,/update/json,/admin/logging,/admin/threads [junit4] 2> 3859889 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859890 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859890 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859903 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 3859903 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 3859905 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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=10, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=99.6337890625, floorSegmentMB=1.8037109375, forceMergeDeletesPctAllowed=27.471344710731763, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 3859916 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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@b8ba552f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11c8a4b7; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 3859916 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 3859916 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@74140090[collection1] main [junit4] 2> 3859917 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859917 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859917 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859918 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3859918 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 3859918 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859919 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859920 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859920 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 3859920 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3859924 INFO (searcherExecutor-7682-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@74140090[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3859926 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859927 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859927 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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> 3859927 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta 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 1523236335509307392 [junit4] 2> 3859927 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 0 ms to seed version buckets with highest version 1523236335509307392 [junit4] 2> 3859927 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 3859928 INFO (coreLoadExecutor-7681-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 3859928 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta 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:50067/i/ta collection:control_collection shard:shard1 [junit4] 2> 3859929 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta 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> 3859933 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta 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/95202270476238855-core_node1-n_0000000000 [junit4] 2> 3859934 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 3859936 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3859937 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3859937 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta 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> 3859937 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:50067/i/ta/collection1/ [junit4] 2> 3859937 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3859937 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:50067/i/ta/collection1/ has no replicas [junit4] 2> 3859937 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta 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:50067/i/ta/collection1/ shard1 [junit4] 2> 3859937 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3859938 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] 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> 3859939 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] 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> 3859941 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 3859944 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] 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:50067/i/ta";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 3859947 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3859947 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] 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> 3859949 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 3859995 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:50067/i/ta/collection1/ and leader is https://127.0.0.1:50067/i/ta/collection1/ [junit4] 2> 3859995 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta 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:50067/i/ta [junit4] 2> 3859995 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3859995 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 3859995 INFO (coreZkRegister-7675-thread-1-processing-c:control_collection n:127.0.0.1:50067_i%2Fta s:shard1 r:core_node1 x:collection1) [n:127.0.0.1:50067_i%2Fta 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> 3859996 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3859997 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:50067_i%2Fta", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"https://127.0.0.1:50067/i/ta";, [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 3859998 INFO (OverseerStateUpdate-95202270476238855-127.0.0.1:50067_i%2Fta-n_0000000000) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:50067_i%2Fta", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"https://127.0.0.1:50067/i/ta";, [junit4] 2> "numShards":"1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3860100 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] 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> 3860102 INFO (zkCallback-2470-thread-1-processing-n:127.0.0.1:50067_i%2Fta) [n:127.0.0.1:50067_i%2Fta ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 3860397 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3860398 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3860399 INFO (zkCallback-2472-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@513133a9 name:ZooKeeperConnection Watcher:127.0.0.1:52626/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3860399 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3860400 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3860400 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3860402 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3860490 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001/cores/collection1 [junit4] 2> 3860491 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001 [junit4] 2> 3860492 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 3860496 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5684a235{/i/ta,null,AVAILABLE} [junit4] 2> 3860500 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.e.j.s.ServerConnector Started ServerConnector@734c17c1{SSL-http/1.1}{127.0.0.1:60649} [junit4] 2> 3860500 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.e.j.s.Server Started @3863878ms [junit4] 2> 3860501 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:56525/hdfs__localhost_56525__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001_tempDir-002_jetty1, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001/cores, hostContext=/i/ta, solrconfig=solrconfig.xml, hostPort=60649} [junit4] 2> 3860501 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 3860501 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001/' [junit4] 2> 3860518 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3860518 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3860521 INFO (zkCallback-2473-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7fb91948 name:ZooKeeperConnection Watcher:127.0.0.1:52626/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3860521 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3860521 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3860523 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 3860523 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001/solr.xml [junit4] 2> 3860537 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001/cores [junit4] 2> 3860537 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.CoreContainer New CoreContainer 1536798069 [junit4] 2> 3860537 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001/] [junit4] 2> 3860538 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001/lib [junit4] 2> 3860538 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] 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-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C71A858F30673F1A-001/shard-1-001/lib). [junit4] 2> 3860546 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] 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> 3860548 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3860551 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3860551 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3860551 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52626/solr [junit4] 2> 3860551 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3860551 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3860552 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3860553 INFO (zkCallback-2475-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6211acf5 name:ZooKeeperConnection Watcher:127.0.0.1:52626 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3860553 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3860554 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C71A858F30673F1A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3860555 INFO (TEST-HdfsCollectionsAPIDistri [...truncated too long message...] tlyTests-5.3/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/build/analysis/icu/lucene-analyzers-icu-5.3.2-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/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.7/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-5.3/solr/build/solr-core/test/temp/junit4-J2-20160113_064507_573.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160113_064507_573.suites [junit4] ERROR: JVM J2 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:262) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [junit4] at java.lang.Thread.run(Thread.java:745) BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003: 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: 204 minutes 44 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
