Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/924/
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Captured an uncaught exception in thread: Thread[id=52426, name=collection3,
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=52426, name=collection3, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
at
__randomizedtesting.SeedInfo.seed([ED50840303638498:6504BBD9AD9FE960]:0)
Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:39255/o_wnb/d: Could not find collection :
awholynewstresscollection_collection3_0
at __randomizedtesting.SeedInfo.seed([ED50840303638498]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:893)
Build Log:
[...truncated 11396 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_ED50840303638498-001/init-core-data-001
[junit4] 2> 1535351 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[ED50840303638498]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
[junit4] 2> 1535351 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[ED50840303638498]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
/o_wnb/d
[junit4] 2> 1535355 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1535355 INFO (Thread-46015) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1535355 INFO (Thread-46015) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1535455 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.ZkTestServer start zk server on port:51825
[junit4] 2> 1535455 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1535456 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1535458 INFO (zkCallback-1355-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@254cfef3
name:ZooKeeperConnection Watcher:127.0.0.1:51825 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1535458 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1535458 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1535458 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1535461 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1535462 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1535463 INFO (zkCallback-1356-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4eefb97f
name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1535463 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1535463 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1535463 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 1535466 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 1535468 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 1535469 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 1535471 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535471 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1535474 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535474 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 1535476 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535476 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1535478 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535478 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1535480 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535480 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 1535481 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535482 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 1535483 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535484 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1535485 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535485 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1535487 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535487 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1535489 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535489 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1535490 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535491 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1535493 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1535494 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1535495 INFO (zkCallback-1357-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@ddb2fd6 name:ZooKeeperConnection
Watcher:127.0.0.1:51825 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1535495 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1535495 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1535496 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1535497 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1535498 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1535498 INFO (zkCallback-1358-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@12bff42 name:ZooKeeperConnection
Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1535499 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1535499 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1535499 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535499 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 1535501 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535502 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
[junit4] 2> 1535503 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535504 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
[junit4] 2> 1535505 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535505 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath:
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1535507 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535507 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
[junit4] 2> 1535509 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535509 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
[junit4] 2> 1535510 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535511 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
[junit4] 2> 1535512 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535512 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 1535514 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535514 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 1535518 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535518 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 1535521 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535521 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
[junit4] 2> 1535524 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535524 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
[junit4] 2> 1535613 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/control-001/cores/collection1
[junit4] 2> 1535615 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1535617 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@ac15012{/o_wnb/d,null,AVAILABLE}
[junit4] 2> 1535617 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@293c5e85{HTTP/1.1}{127.0.0.1:60845}
[junit4] 2> 1535617 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.e.j.s.Server Started @1538923ms
[junit4] 2> 1535617 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/tempDir-001/control/data,
hostContext=/o_wnb/d, hostPort=60845,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores}
[junit4] 2> 1535618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 1535618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/control-001'
[junit4] 2> 1535618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1535618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1535618 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1535619 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1535620 INFO (zkCallback-1359-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3c287497
name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1535620 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1535620 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1535621 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1535621 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/control-001/solr.xml
[junit4] 2> 1535626 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores
[junit4] 2> 1535626 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.CoreContainer New CoreContainer 2108093829
[junit4] 2> 1535626 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/control-001]
[junit4] 2> 1535626 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/lib
[junit4] 2> 1535626 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1535628 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1535628 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1535628 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1535628 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51825/solr
[junit4] 2> 1535629 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1535629 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1535629 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1535631 INFO (zkCallback-1362-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@493d2044
name:ZooKeeperConnection Watcher:127.0.0.1:51825 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1535632 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1535633 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1535635 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Waiting for
client to connect to ZooKeeper
[junit4] 2> 1535636 INFO
(zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@cdf70a6 name:ZooKeeperConnection
Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1535636 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Client is
connected to ZooKeeper
[junit4] 2> 1535636 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer/queue
[junit4] 2> 1535639 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 1535642 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 1535644 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 1535646 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 1535649 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1535650 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1535651 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/clusterstate.json
[junit4] 2> 1535653 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/security.json
[junit4] 2> 1535654 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60845_o_wnb%2Fd
[junit4] 2> 1535654 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:60845_o_wnb%2Fd
[junit4] 2> 1535655 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect
[junit4] 2> 1535656 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 1535657 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1535658 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.LeaderElector Joined leadership
election with path:
/overseer_elect/election/95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000
[junit4] 2> 1535658 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.OverseerElectionContext I am going to
be the leader 127.0.0.1:60845_o_wnb%2Fd
[junit4] 2> 1535658 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/leader
[junit4] 2> 1535659 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer Overseer
(id=95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) starting
[junit4] 2> 1535661 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/overseer/queue-work
[junit4] 2> 1535666 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.OverseerAutoReplicaFailoverThread
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=10000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1535666 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.ZkStateReader Updating cluster
state from ZooKeeper...
[junit4] 2> 1535667 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer Starting to work on the main
queue
[junit4] 2> 1535667 INFO
(OverseerCollectionConfigSetProcessor-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.OverseerTaskProcessor Process
current queue of overseer operations
[junit4] 2> 1535682 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CoreContainer Security conf doesn't
exist. Skipping setup for authorization module.
[junit4] 2> 1535682 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CoreContainer No authentication
plugin used.
[junit4] 2> 1535682 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Looking for
core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores
[junit4] 2> 1535683 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CoreDescriptor Created
CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json,
collection=control_collection, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1535683 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Found core
collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1
[junit4] 2> 1535683 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions
[junit4] 2> 1535685 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
[junit4] 2> 1535685 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.s.SolrDispatchFilter
SolrDispatchFilter.init() done
[junit4] 2> 1535685 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.ZkController publishing state=down
[junit4] 2> 1535685 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.ZkController numShards not found on descriptor - reading it from system
property
[junit4] 2> 1535687 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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:60845/o_wnb/d";,
[junit4] 2> "node_name":"127.0.0.1:60845_o_wnb%2Fd",
[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> 1535687 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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:60845/o_wnb/d";,
[junit4] 2> "node_name":"127.0.0.1:60845_o_wnb%2Fd",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1535687 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.ZkController look for our core node name
[junit4] 2> 1535688 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ClusterStateMutator building a new
cName: control_collection
[junit4] 2> 1535688 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Assigning new node
to shard shard=shard1
[junit4] 2> 1535689 INFO
(zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1536688 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 1536688 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 1536689 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1536689 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 1536689 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 1536689 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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_ED50840303638498-001/control-001/cores/collection1'
[junit4] 2> 1536689 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1536689 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1536691 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1536696 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1536710 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1536719 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1536725 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1536828 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1536833 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1536834 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1536842 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1536844 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1536846 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 1536846 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 1536848 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536848 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 1536848 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1536848 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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_ED50840303638498-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1/data/]
[junit4] 2> 1536848 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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@79e3e69b
[junit4] 2> 1536849 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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_ED50840303638498-001/control-001/cores/collection1/data
[junit4] 2> 1536849 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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_ED50840303638498-001/control-001/cores/collection1/data/index/
[junit4] 2> 1536849 WARN
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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_ED50840303638498-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1536849 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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_ED50840303638498-001/control-001/cores/collection1/data/index
[junit4] 2> 1536849 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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=19, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1536850 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6cf74af9
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12f2ded6),segFN=segments_1,generation=1}
[junit4] 2> 1536850 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1536850 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1)
[n:127.0.0.1:60845_o_wnb%2Fd 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_ED50840303638498-001/control-001/cores/collection1/data/
[junit4] 2> 1536850 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1)
[n:127.0.0.1:60845_o_wnb%2Fd 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_ED50840303638498-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 1536854 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1536854 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1536854 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1536854 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1536855 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1536855 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536855 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536855 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536855 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536855 WARN
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler
{type = requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1536857 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1536858 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1536859 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1536860 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1536863 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1536863 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536864 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536864 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536865 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1536865 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1536865 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=5, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=36.9208984375,
floorSegmentMB=0.84375, forceMergeDeletesPctAllowed=12.718728979334532,
segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4737649688065727
[junit4] 2> 1536865 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6cf74af9
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12f2ded6),segFN=segments_1,generation=1}
[junit4] 2> 1536865 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1536865 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17691bd[collection1]
main]
[junit4] 2> 1536865 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536866 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536866 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536866 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 1536867 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1536867 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536867 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536867 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536867 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1536867 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1536868 INFO
(searcherExecutor-3677-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@17691bd[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1536868 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536868 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536868 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536868 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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 1525285457423761408
[junit4] 2> 1536870 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd 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 1525285457423761408
[junit4] 2> 1536870 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 1536871 INFO
(coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1536871 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd 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:60845/o_wnb/d collection:control_collection
shard:shard1
[junit4] 2> 1536872 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536874 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd 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/95330340989894662-core_node1-n_0000000000
[junit4] 2> 1536875 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process
for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000
[junit4] 2> 1536876 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 1536876 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1536876 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1536876 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:60845/o_wnb/d/collection1/
[junit4] 2> 1536877 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1536877 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60845/o_wnb/d/collection1/
has no replicas
[junit4] 2> 1536878 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.c.SolrZkClient makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 1536879 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader
registration node
[junit4] 2> 1536881 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd 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:60845/o_wnb/d/collection1/ shard1
[junit4] 2> 1536881 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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:60845/o_wnb/d";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1536983 INFO
(zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1537032 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController We are
http://127.0.0.1:60845/o_wnb/d/collection1/ and leader is
http://127.0.0.1:60845/o_wnb/d/collection1/
[junit4] 2> 1537032 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd 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:60845/o_wnb/d
[junit4] 2> 1537032 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1537032 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 1537032 INFO
(coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60845_o_wnb%2Fd 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> 1537034 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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:60845/o_wnb/d";,
[junit4] 2> "node_name":"127.0.0.1:60845_o_wnb%2Fd",
[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> 1537034 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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:60845/o_wnb/d";,
[junit4] 2> "node_name":"127.0.0.1:60845_o_wnb%2Fd",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1537136 INFO
(zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1537186 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1537187 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1537188 INFO (zkCallback-1365-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@c6350 name:ZooKeeperConnection
Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1537189 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1537189 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1537189 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1537193 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1537193 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 1537193 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1537194 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1537196 INFO (zkCallback-1366-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3aed3e24
name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1537196 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1537197 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1537199 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer processMessage: queueSize:
1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 3
[junit4] 2> 1537199 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ClusterStateMutator building a new
cName: collection1
[junit4] 2> 1537287 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/shard-1-001/cores/collection1
[junit4] 2> 1537287 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/shard-1-001
[junit4] 2> 1537288 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1537290 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@414e9ecf{/o_wnb/d,null,AVAILABLE}
[junit4] 2> 1537290 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@2e6a7b5d{HTTP/1.1}{127.0.0.1:35428}
[junit4] 2> 1537290 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.e.j.s.Server Started @1540597ms
[junit4] 2> 1537291 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/o_wnb/d, hostPort=35428,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores}
[junit4] 2> 1537291 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 1537291 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/shard-1-001'
[junit4] 2> 1537291 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1537291 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1537291 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1537292 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1537293 INFO (zkCallback-1367-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@c22cd41 name:ZooKeeperConnection
Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1537293 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1537294 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1537295 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1537295 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/shard-1-001/solr.xml
[junit4] 2> 1537300 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores
[junit4] 2> 1537300 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.CoreContainer New CoreContainer 249742675
[junit4] 2> 1537300 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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_ED50840303638498-001/shard-1-001]
[junit4] 2> 1537301 WARN
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/lib
[junit4] 2> 1537301 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
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> 1537302 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1537302 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1537302 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1537303 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51825/solr
[junit4] 2> 1537303 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1537303 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1537304 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1537304 INFO (zkCallback-1370-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4f3c4fa9
name:ZooKeeperConnection Watcher:127.0.0.1:51825 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1537305 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1537306 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ]
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1537308 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Waiting for
client to connect to ZooKeeper
[junit4] 2> 1537309 INFO
(zkCallback-1371-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@1bfe89ca
name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1537309 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Client is
connected to ZooKeeper
[junit4] 2> 1537310 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ZkStateWriter going to
create_collection /collections/collection1/state.json
[junit4] 2> 1537317 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ZkStateReader Updating cluster
state from ZooKeeper...
[junit4] 2> 1538325 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35428_o_wnb%2Fd
[junit4] 2> 1538325 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:35428_o_wnb%2Fd
[junit4] 2> 1538326 INFO (zkCallback-1365-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> 1538326 INFO
(zkCallback-1371-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd ] 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> 1538327 INFO
(zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1538329 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1538329 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.LeaderElector Joined leadership
election with path:
/overseer_elect/election/95330340989894667-127.0.0.1:35428_o_wnb%2Fd-n_0000000001
[junit4] 2> 1538331 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000
to know if I could be the leader
[junit4] 2> 1538353 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CoreContainer Security conf doesn't
exist. Skipping setup for authorization module.
[junit4] 2> 1538353 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CoreContainer No authentication
plugin used.
[junit4] 2> 1538354 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Looking for
core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores
[junit4] 2> 1538355 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CoreDescriptor Created
CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json,
collection=collection1, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1538355 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Found core
collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores/collection1
[junit4] 2> 1538355 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions
[junit4] 2> 1538356 INFO
(coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1]
o.a.s.c.ZkController publishing state=down
[junit4] 2> 1538356 INFO
(coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1]
o.a.s.c.ZkController numShards not found on descriptor - reading it from system
property
[junit4] 2> 1538357 INFO
(coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1]
o.a.s.c.ZkController Registering watch for collection collection1
[junit4] 2> 1538358 INFO
(coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1]
o.a.s.c.c.ZkStateReader addZkWatch [collection1]
[junit4] 2> 1538358 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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:35428/o_wnb/d";,
[junit4] 2> "node_name":"127.0.0.1:35428_o_wnb%2Fd",
[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> 1538358 INFO
(coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1]
o.a.s.c.c.ZkStateReader Add data for [collection1] ver [0]
[junit4] 2> 1538358 INFO
(coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1]
o.a.s.c.ZkController look for our core node name
[junit4] 2> 1538358 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] 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:35428/o_wnb/d";,
[junit4] 2> "node_name":"127.0.0.1:35428_o_wnb%2Fd",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1538358 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Collection already
exists with numShards=2
[junit4] 2> 1538359 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Assigning new node
to shard shard=shard2
[junit4] 2> 1538359 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
[junit4] 2> 1538359 INFO
(TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498])
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.s.SolrDispatchFilter
SolrDispatchFilter.init() done
[junit4] 2> 1538461 INFO
(OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000)
[n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ZkStateWriter going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1538461 INFO
(zkCallback-1371-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ZkStateReader A cluster state
change: [WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1538462 INFO
(zkCallback-1371-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ZkStateReader Updating data for
[collection1] from [0] to [1]
[junit4] 2> 1539358 INFO
(coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd)
[n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1]
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[...truncated too long message...]
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection4_0/leader_elect/shard2/election/95330340989894673-core_node18-n_0000000001
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection4_0/leader_elect/shard1/election/95330340989894670-core_node19-n_0000000001
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection2_0/leader_elect/shard2/election/95330340989894667-core_node17-n_0000000001
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection5_0/leader_elect/shard5/election/95330340989894673-core_node7-n_0000000000
[junit4] 2> 2
/solr/collections/acollectionafterbaddelete/state.json
[junit4] 2> 2
/solr/collections/addReplicaColl/leader_elect/shard1/election/95330340989894676-core_node2-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection3_0/leader_elect/shard5/election/95330340989894667-core_node5-n_0000000001
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection2_0/leader_elect/shard4/election/95330340989894673-core_node13-n_0000000001
[junit4] 2> 2
/solr/collections/addReplicaColl/leader_elect/shard2/election/95330340989894673-core_node3-n_0000000000
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection3_0/leader_elect/shard3/election/95330340989894673-core_node17-n_0000000001
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection2_0/leader_elect/shard3/election/95330340989894673-core_node4-n_0000000001
[junit4] 2> 2
/solr/collections/halfdeletedcollection/state.json
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection3_0/leader_elect/shard1/election/95330340989894670-core_node15-n_0000000001
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard2/election/95330340989894676-core_node1-n_0000000001
[junit4] 2> 2
/solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard5/election/95330340989894670-core_node12-n_0000000001
[junit4] 2> 2
/solr/collections/awholynewcollection_4/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/collections
[junit4] 2> 19 /solr/live_nodes
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=test
-Dtests.seed=ED50840303638498 -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=tr -Dtests.timezone=Australia/Canberra -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 241s J1 | CollectionsAPIDistributedZkTest.test <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=52426, name=collection3, state=RUNNABLE,
group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at
__randomizedtesting.SeedInfo.seed([ED50840303638498:6504BBD9AD9FE960]:0)
[junit4] > Caused by:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:39255/o_wnb/d: Could not find collection :
awholynewstresscollection_collection3_0
[junit4] > at
__randomizedtesting.SeedInfo.seed([ED50840303638498]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:893)
[junit4] 2> 1776672 INFO
(SUITE-CollectionsAPIDistributedZkTest-seed#[ED50840303638498]-worker)
[n:127.0.0.1:39255_o_wnb%2Fd c:addReplicaColl s:shard2 r:core_node6
x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> Feb 04, 2016 10:43:25 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60):
{other_tl1=Lucene50(blocksize=128), range_facet_l_dv=FSTOrd50,
_version_=Lucene50(blocksize=128),
multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval),
a_t=Lucene50(blocksize=128), intDefault=Lucene50(blocksize=128),
a_i1=Lucene50(blocksize=128), id=FSTOrd50,
range_facet_i_dv=Lucene50(blocksize=128), text=PostingsFormat(name=Direct),
range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)},
docValues:{range_facet_l_dv=DocValuesFormat(name=Memory),
range_facet_i_dv=DocValuesFormat(name=Lucene54),
timestamp=DocValuesFormat(name=Lucene54)},
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=tr,
timezone=Australia/Canberra
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_66 (64-bit)/cpus=4,threads=1,free=151245272,total=478674944
[junit4] 2> NOTE: All tests run in this JVM: [AsyncMigrateRouteKeyTest,
CollectionReloadTest, TestDefaultSearchFieldResource,
TestPartialUpdateDeduplication, ZkControllerTest, TestSolrCLIRunExample,
BlockJoinFacetRandomTest, TestAuthorizationFramework, DistributedQueueTest,
TestFieldTypeResource, ConnectionManagerTest, MergeStrategyTest,
TestRequestForwarding, ChaosMonkeyNothingIsSafeTest, TestSortByMinMaxFunction,
TestAddFieldRealTimeGet, SmileWriterTest, CdcrUpdateLogTest, ZkCLITest,
DistributedQueryComponentCustomSortTest, IndexBasedSpellCheckerTest,
TestSolrCoreProperties, BigEndianAscendingWordSerializerTest,
TestPivotHelperCode, SaslZkACLProviderTest, TestBulkSchemaConcurrent,
TestQuerySenderListener, TestRawTransformer, HighlighterMaxOffsetTest,
TestEmbeddedSolrServerConstructors, MoreLikeThisHandlerTest,
SpellPossibilityIteratorTest, TestJsonFacets, TestCursorMarkWithoutUniqueKey,
TestLFUCache, SchemaVersionSpecificBehaviorTest, TestDistributedGrouping,
TestUniqueKeyFieldResource, AnalyticsMergeStrategyTest, SolrIndexConfigTest,
SolrIndexSplitterTest, SparseHLLTest, ChaosMonkeySafeLeaderTest,
DocumentBuilderTest, TestSimpleTrackingShardHandler,
DistributedExpandComponentTest, DistanceUnitsTest, FastVectorHighlighterTest,
HdfsWriteToMultipleCollectionsTest, TestFilteredDocIdSet,
TermVectorComponentTest, ConvertedLegacyTest, ExitableDirectoryReaderTest,
TestAnalyzeInfixSuggestions, HdfsSyncSliceTest, HdfsChaosMonkeySafeLeaderTest,
DistributedIntervalFacetingTest, TestSolrDynamicMBean,
NotRequiredUniqueKeyTest, CSVRequestHandlerTest, HdfsBasicDistributedZk2Test,
TestComplexPhraseQParserPlugin, BadCopyFieldTest, TestCloudPivotFacet,
SolrPluginUtilsTest, TestSchemaManager,
TestPerFieldSimilarityWithDefaultOverride, ZkSolrClientTest, SuggesterFSTTest,
DistanceFunctionTest, SuggestComponentTest, OverseerRolesTest,
TestInfoStreamLogging, TestDocumentBuilder, FullHLLTest,
SpellCheckCollatorTest, TestReqParamsAPI, TestSystemIdResolver,
NumericFieldsTest, TestSurroundQueryParser, SpatialFilterTest, TestDistribIDF,
TestReloadDeadlock, DocValuesTest, ShardRoutingCustomTest, TestConfigSets,
TestManagedSchemaFieldTypeResource, RecoveryAfterSoftCommitTest,
BasicFunctionalityTest, AliasIntegrationTest, TestExceedMaxTermLength,
TestTrie, BasicDistributedZk2Test, UnloadDistributedZkTest,
CollectionsAPIDistributedZkTest]
[junit4] Completed [188/575 (1!)] on J1 in 241.80s, 1 test, 1 error <<<
FAILURES!
[...truncated 1254 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:747:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:684:
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:524:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1457:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1014:
There were test failures: 575 suites (8 ignored), 2316 tests, 1 error, 33
ignored (18 assumptions) [seed: ED50840303638498]
Total time: 295 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
