Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/782/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=3732, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3732, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:34748: Could not find collection : awholynewstresscollection_collection0_2 at __randomizedtesting.SeedInfo.seed([30C37AFE985C4B24]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=31433, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=31433, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:55220/g_ju: Could not find collection : awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([30C37AFE985C4B24]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) Build Log: [...truncated 9767 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/init-core-data-001 [junit4] 2> 393404 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[30C37AFE985C4B24]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 393404 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[30C37AFE985C4B24]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 393409 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 393409 INFO (Thread-1149) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 393409 INFO (Thread-1149) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 393509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.ZkTestServer start zk server on port:35335 [junit4] 2> 393510 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 393511 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 393517 INFO (zkCallback-360-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3420f7da name:ZooKeeperConnection Watcher:127.0.0.1:35335 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 393517 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 393517 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 393517 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 393519 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 393520 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 393521 INFO (zkCallback-361-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@630989c8 name:ZooKeeperConnection Watcher:127.0.0.1:35335/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 393521 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 393521 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 393521 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 393523 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 393525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 393532 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 393533 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 393534 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 393536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 393536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 393538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 393538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 393541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 393541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 393542 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 393543 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 393544 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 393544 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 393552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 393552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 393554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 393554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 393556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 393556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 393558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 393558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 393559 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 393559 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 393562 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 393570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 393579 INFO (zkCallback-362-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@49bd884c name:ZooKeeperConnection Watcher:127.0.0.1:35335 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 393579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 393580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 393580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 393583 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 393587 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 393589 INFO (zkCallback-363-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@21f85e8e name:ZooKeeperConnection Watcher:127.0.0.1:35335/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 393589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 393589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 393590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 393590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 393594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 393595 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 393598 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 393599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 393600 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 393600 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 393602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 393602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 393604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 393604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 393605 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 393606 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 393609 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 393609 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 393610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 393610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 393612 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 393612 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 393614 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 393614 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 393615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 393615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 394033 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1 [junit4] 2> 394041 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 394072 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@35cf3341{/,null,AVAILABLE} [junit4] 2> 394073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7cb82d36{HTTP/1.1}{127.0.0.1:42527} [junit4] 2> 394073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.e.j.s.Server Started @397504ms [junit4] 2> 394073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/tempDir-001/control/data, hostContext=/, hostPort=42527, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores} [junit4] 2> 394074 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 394074 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/' [junit4] 2> 394089 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 394091 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 394099 INFO (zkCallback-364-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6feb3659 name:ZooKeeperConnection Watcher:127.0.0.1:35335/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 394099 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 394100 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 394101 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 394101 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/solr.xml [junit4] 2> 394107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores [junit4] 2> 394107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.CoreContainer New CoreContainer 968112429 [junit4] 2> 394107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/] [junit4] 2> 394108 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/lib [junit4] 2> 394108 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/lib). [junit4] 2> 394115 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] 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> 394117 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 394117 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 394117 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 394117 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35335/solr [junit4] 2> 394118 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 394118 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 394123 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 394127 INFO (zkCallback-366-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3307ecec name:ZooKeeperConnection Watcher:127.0.0.1:35335 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 394130 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 394130 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 394140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 394144 INFO (zkCallback-367-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2bba40dd name:ZooKeeperConnection Watcher:127.0.0.1:35335/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 394144 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 394145 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 394147 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 394150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 394151 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 394153 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 394155 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 394157 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 394158 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 394160 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 394162 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42527_ [junit4] 2> 394162 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:42527_ [junit4] 2> 394164 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 394167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 394168 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 394169 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94444131236184070-127.0.0.1:42527_-n_0000000000 [junit4] 2> 394169 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42527_ [junit4] 2> 394169 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 394173 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.Overseer Overseer (id=94444131236184070-127.0.0.1:42527_-n_0000000000) starting [junit4] 2> 394175 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 394180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 394189 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 394190 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 394191 INFO (OverseerCollectionConfigSetProcessor-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 394202 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 394225 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 394225 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 394226 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores [junit4] 2> 394227 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 394227 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/ [junit4] 2> 394227 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 394232 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 394232 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 394235 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 394235 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:42527_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 394236 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42527";, [junit4] 2> "node_name":"127.0.0.1:42527_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 394237 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42527";, [junit4] 2> "node_name":"127.0.0.1:42527_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 394237 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 394237 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 394238 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 394239 INFO (zkCallback-367-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] 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> 394243 INFO (zkCallback-367-thread-2-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] 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> 395238 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 395238 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 395238 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 395238 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 395240 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 395240 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/' [junit4] 2> 395255 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 395265 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 395269 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 395283 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 395285 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 395293 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 395400 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 395406 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 395407 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 395417 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 395419 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 395421 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 395422 WARN (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 395422 WARN (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 395422 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 395423 WARN (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 395423 WARN (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 395424 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 395424 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 395424 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 395424 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 395424 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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@ba75f7 [junit4] 2> 395425 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/data [junit4] 2> 395425 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/data/index/ [junit4] 2> 395425 WARN (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 395426 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/data/index [junit4] 2> 395426 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 395426 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@34f8bffb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c49795e),segFN=segments_1,generation=1} [junit4] 2> 395427 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 395430 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 395431 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 395431 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 395431 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 395431 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 395431 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395432 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395432 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395432 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395434 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 395435 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 395437 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 395437 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 395439 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42527_ 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 /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/data/ [junit4] 2> 395441 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 395441 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 395443 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395457 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 395457 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 395458 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 395458 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 395459 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@12b73f88 [junit4] 2> 395511 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@34f8bffb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c49795e),segFN=segments_1,generation=1} [junit4] 2> 395511 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 395511 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7ee96b62[collection1] main [junit4] 2> 395511 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395512 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395512 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395513 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 395513 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 395513 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395513 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395514 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395514 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 395514 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 395514 INFO (searcherExecutor-989-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7ee96b62[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 395515 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395515 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 395515 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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> 395515 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ 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 1511106101985148928 [junit4] 2> 395517 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1511106101985148928 [junit4] 2> 395517 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 395518 INFO (coreLoadExecutor-988-thread-1-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 395519 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:42527 collection:control_collection shard:shard1 [junit4] 2> 395522 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ 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> 395529 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ 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/94444131236184070-core_node1-n_0000000000 [junit4] 2> 395531 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 395533 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 395533 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] 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> 395533 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ 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> 395534 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42527/collection1/ [junit4] 2> 395534 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 395534 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:42527/collection1/ has no replicas [junit4] 2> 395534 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42527/collection1/ shard1 [junit4] 2> 395535 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 395540 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:42527";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 395642 INFO (zkCallback-367-thread-3-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] 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> 395642 INFO (zkCallback-367-thread-2-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] 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> 395690 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:42527/collection1/ and leader is http://127.0.0.1:42527/collection1/ [junit4] 2> 395691 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42527 [junit4] 2> 395691 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 395691 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 395691 INFO (coreZkRegister-982-thread-1-processing-n:127.0.0.1:42527_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42527_ 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> 395693 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42527";, [junit4] 2> "node_name":"127.0.0.1:42527_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 395693 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42527";, [junit4] 2> "node_name":"127.0.0.1:42527_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 395737 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 395738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 395739 INFO (zkCallback-369-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c455c5e name:ZooKeeperConnection Watcher:127.0.0.1:35335/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 395739 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 395740 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 395740 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 395743 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 395796 INFO (zkCallback-367-thread-2-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] 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> 395796 INFO (zkCallback-369-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 395796 INFO (zkCallback-367-thread-3-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] 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> 396068 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/cores/collection1 [junit4] 2> 396069 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001 [junit4] 2> 396070 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 396073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ac9db14{/,null,AVAILABLE} [junit4] 2> 396073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.e.j.s.ServerConnector Started ServerConnector@14aabbae{HTTP/1.1}{127.0.0.1:48939} [junit4] 2> 396073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.e.j.s.Server Started @399504ms [junit4] 2> 396074 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=48939, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/cores} [junit4] 2> 396074 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 396074 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/' [junit4] 2> 396095 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 396097 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 396097 INFO (zkCallback-370-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@332c2496 name:ZooKeeperConnection Watcher:127.0.0.1:35335/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 396098 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 396098 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 396100 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 396100 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/solr.xml [junit4] 2> 396105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/cores [junit4] 2> 396105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.CoreContainer New CoreContainer 99389779 [junit4] 2> 396105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/] [junit4] 2> 396105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/lib [junit4] 2> 396106 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/lib). [junit4] 2> 396113 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] 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> 396114 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 396114 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 396114 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 396115 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35335/solr [junit4] 2> 396115 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 396115 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 396129 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 396129 INFO (zkCallback-372-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@43cebe31 name:ZooKeeperConnection Watcher:127.0.0.1:35335 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 396130 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 396130 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 396132 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 396134 INFO (zkCallback-373-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@83b414e name:ZooKeeperConnection Watcher:127.0.0.1:35335/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 396134 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 396140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 397144 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48939_ [junit4] 2> 397145 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:48939_ [junit4] 2> 397146 INFO (zkCallback-367-thread-2-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 397146 INFO (zkCallback-367-thread-3-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 397146 INFO (zkCallback-369-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 397146 INFO (zkCallback-373-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 397148 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 397149 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94444131236184074-127.0.0.1:48939_-n_0000000001 [junit4] 2> 397149 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94444131236184070-127.0.0.1:42527_-n_0000000000 to know if I could be the leader [junit4] 2> 397158 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 397158 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 397159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/cores [junit4] 2> 397160 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 397160 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/cores/collection1/ [junit4] 2> 397160 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 397167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 397167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[30C37AFE985C4B24]) [n:127.0.0.1:48939_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 397171 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 397172 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 397176 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:48939";, [junit4] 2> "node_name":"127.0.0.1:48939_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 397177 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:48939";, [junit4] 2> "node_name":"127.0.0.1:48939_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 397177 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 397177 INFO (OverseerStateUpdate-94444131236184070-127.0.0.1:42527_-n_0000000000) [n:127.0.0.1:42527_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 397180 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 397280 INFO (zkCallback-369-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 397280 INFO (zkCallback-373-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 397280 INFO (zkCallback-367-thread-3-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 397280 INFO (zkCallback-367-thread-2-processing-n:127.0.0.1:42527_) [n:127.0.0.1:42527_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 398181 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 398181 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 398182 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 398182 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 398182 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 398182 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_30C37AFE985C4B24-001/shard-1-001/cores/collection1/' [junit4] 2> 398199 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 398204 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 398207 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 398217 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 398218 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 398222 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 398319 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 398325 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 398326 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 398336 INFO (coreLoadExecutor-999-thread-1-processing-n:127.0.0.1:48939_) [n:127.0.0.1:48939_ c:collection1 x:collecti [...truncated too long message...] ultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 4635211 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[30C37AFE985C4B24]-worker) [n:127.0.0.1:55220_g_ju c:addReplicaColl s:shard1 r:core_node2 x:addReplicaColl_shard1_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Sep 01, 2015 7:35:19 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene53, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=es_VE, timezone=Asia/Dacca [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=2,free=193793272,total=534249472 [junit4] 2> NOTE: All tests run in this JVM: [TestTrackingShardHandlerFactory, TestFoldingMultitermQuery, BlockDirectoryTest, DeleteShardTest, TestSimpleTrackingShardHandler, SpellingQueryConverterTest, CollectionsAPIAsyncDistributedZkTest, TestSortingResponseWriter, HighlighterMaxOffsetTest, TestMissingGroups, CachingDirectoryFactoryTest, DisMaxRequestHandlerTest, TestSortByMinMaxFunction, CacheHeaderTest, TestConfig, StandardRequestHandlerTest, TestArbitraryIndexDir, RemoteQueryErrorTest, CoreAdminCreateDiscoverTest, CurrencyFieldOpenExchangeTest, TestSerializedLuceneMatchVersion, CursorPagingTest, BigEndianAscendingWordSerializerTest, UUIDUpdateProcessorFallbackTest, TestPKIAuthenticationPlugin, PingRequestHandlerTest, TestManagedSchemaFieldResource, TestInitQParser, SolrCoreTest, PolyFieldTest, SolrRequestParserTest, TestSolrConfigHandlerConcurrent, TestDynamicFieldResource, TestRawResponseWriter, RollingRestartTest, TestExactStatsCache, TestJsonRequest, TestComponentsName, HdfsBasicDistributedZk2Test, IndexSchemaRuntimeFieldTest, TestDocumentBuilder, TestRandomDVFaceting, ConnectionManagerTest, TestDownShardTolerantSearch, FileBasedSpellCheckerTest, TestDistribIDF, TestConfigSetImmutable, BaseCdcrDistributedZkTest, ExternalFileFieldSortTest, TestIBSimilarityFactory, DateFieldTest, TestAtomicUpdateErrorCases, TestBinaryField, CdcrReplicationHandlerTest, TestConfigSetsAPIZkFailure, TestStressLucene, ActionThrottleTest, HttpPartitionTest, CoreMergeIndexesAdminHandlerTest, TestRandomFaceting, RequestLoggingTest, ReplicationFactorTest, TestLazyCores, SOLR749Test, TestBlobHandler, PathHierarchyTokenizerFactoryTest, TestSolrJ, TestFaceting, TestCollationFieldDocValues, ReturnFieldsTest, TestLuceneMatchVersion, TestFieldCollectionResource, TestAnalyzedSuggestions, DistributedQueryElevationComponentTest, PrimUtilsTest, JSONWriterTest, DistributedFacetPivotLargeTest, TestDocSet, TestSchemaResource, EnumFieldTest, DistributedFacetPivotSmallTest, OpenExchangeRatesOrgProviderTest, TestReplicaProperties, TestHdfsUpdateLog, SignatureUpdateProcessorFactoryTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, BasicDistributedZkTest, UnloadDistributedZkTest, OpenCloseCoreStressTest, TestDistributedGrouping, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TestRealTimeGet, TestJoin, TestReload, DistributedTermsComponentTest, SpellCheckComponentTest, TestGroupingSearch, BasicFunctionalityTest, HighlighterTest, ShowFileRequestHandlerTest, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, DocValuesTest, SuggesterTest, SuggesterWFSTTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestUpdate, QueryEqualityTest, SortByFunctionTest, TestRemoteStreaming, XsltUpdateRequestHandlerTest, TestWriterPerf, RequestHandlersTest, TestValueSourceCache, TermVectorComponentTest, RequiredFieldsTest, LoggingHandlerTest, SolrPluginUtilsTest, TestJmxIntegration, QueryParsingTest, JsonLoaderTest, TestBinaryResponseWriter, AlternateDirectoryTest, ResponseLogComponentTest, TestMergePolicyConfig, MultiTermTest, OutputWriterTest, TestSolrCoreProperties, TestPhraseSuggestions, SynonymTokenizerTest, TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, TestDefaultSimilarityFactory, TimeZoneUtilsTest, ScriptEngineTest, PreAnalyzedFieldTest, RAMDirectoryFactoryTest, TestLRUCache, TestUtils, UUIDFieldTest, FileUtilsTest, DistributedMLTComponentTest, DistributedIntervalFacetingTest, AsyncCallRequestStatusResponseTest, AsyncMigrateRouteKeyTest, CollectionReloadTest, CustomCollectionTest, DistribJoinFromCollectionTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, SolrXmlInZkTest, TestCloudInspectUtil, TestCloudPivotFacet, TestCollectionAPI, TestConfigSetsAPIExclusivity, TestCryptoKeys, TestLeaderElectionZkExpiry, TestMiniSolrCloudCluster, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [543/543] on J0 in 963.64s, 1 test, 1 error <<< FAILURES! [...truncated 12 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:514: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 543 suites, 2145 tests, 2 errors, 30 ignored (18 assumptions) Total time: 194 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 Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
