Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/17/

2 tests failed.
FAILED:  
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable

Error Message:
Timeout while trying to assert replication errors

Stack Trace:
java.lang.AssertionError: Timeout while trying to assert replication errors
        at 
__randomizedtesting.SeedInfo.seed([7FFD6E506C30D409:99693423676FB921]:0)
        at 
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable(CdcrReplicationDistributedZkTest.java:140)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.AssertionError
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertTrue(Assert.java:54)
        at 
org.apache.solr.cloud.CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable(CdcrReplicationDistributedZkTest.java:127)
        ... 41 more


FAILED:  org.apache.solr.cloud.UnloadDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=19519, 
name=testExecutor-4520-thread-11, state=RUNNABLE, 
group=TGRP-UnloadDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=19519, name=testExecutor-4520-thread-11, 
state=RUNNABLE, group=TGRP-UnloadDistributedZkTest]
        at 
__randomizedtesting.SeedInfo.seed([7FFD6E506C30D409:F7A9518AC2CCB9F1]:0)
Caused by: java.lang.RuntimeException: 
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting 
response from server at: http://127.0.0.1:43802/tz/zi
        at __randomizedtesting.SeedInfo.seed([7FFD6E506C30D409]:0)
        at 
org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$0(BasicDistributedZkTest.java:583)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured 
while waiting response from server at: http://127.0.0.1:43802/tz/zi
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
        at 
org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$0(BasicDistributedZkTest.java:581)
        ... 4 more
Caused by: java.net.SocketTimeoutException: Read timed out
        at java.net.SocketInputStream.socketRead0(Native Method)
        at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
        at java.net.SocketInputStream.read(SocketInputStream.java:170)
        at java.net.SocketInputStream.read(SocketInputStream.java:141)
        at 
org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
        at 
org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
        at 
org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
        at 
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
        at 
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
        at 
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
        at 
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
        at 
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
        at 
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482)
        ... 8 more




Build Log:
[...truncated 11553 lines...]
   [junit4] Suite: org.apache.solr.cloud.CdcrReplicationDistributedZkTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/init-core-data-001
   [junit4]   2> 466247 INFO  
(SUITE-CdcrReplicationDistributedZkTest-seed#[7FFD6E506C30D409]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 466247 INFO  
(SUITE-CdcrReplicationDistributedZkTest-seed#[7FFD6E506C30D409]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /la/
   [junit4]   2> 466251 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 466251 INFO  (Thread-4879) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 466251 INFO  (Thread-4879) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 466351 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.ZkTestServer start zk server on port:38549
   [junit4]   2> 466351 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 466352 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 466357 INFO  (zkCallback-278-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@173ad086 
name:ZooKeeperConnection Watcher:127.0.0.1:38549 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 466357 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 466358 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 466358 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 466362 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 466364 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 466366 INFO  (zkCallback-279-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@756f81de 
name:ZooKeeperConnection Watcher:127.0.0.1:38549/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 466366 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 466367 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 466367 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 466369 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 466370 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 466372 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 466373 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-cdcr.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 466373 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 466376 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema15.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 466376 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 466379 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 466379 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 466381 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 466381 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 466382 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 466382 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 466384 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 466384 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 466386 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 466387 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 466388 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 466389 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 466390 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 466391 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 466393 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 466393 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 466395 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 466395 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 466399 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testTargetCollectionNotAvailable
   [junit4]   2> 466520 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1
   [junit4]   2> 466521 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 466523 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1edf0cbd{/la,null,AVAILABLE}
   [junit4]   2> 466525 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@5d75bc9d{HTTP/1.1,[http/1.1]}{127.0.0.1:35328}
   [junit4]   2> 466525 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.e.j.s.Server Started @469690ms
   [junit4]   2> 466525 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard1, 
hostContext=/la, hostPort=35328, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores}
   [junit4]   2> 466525 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 466525 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001'
   [junit4]   2> 466525 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr 
(NoInitialContextEx)
   [junit4]   2> 466526 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not 
find system property or JNDI)
   [junit4]   2> 466526 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 466526 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 466528 INFO  (zkCallback-280-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5940a854 
name:ZooKeeperConnection Watcher:127.0.0.1:38549/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 466528 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 466529 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 466530 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 466530 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/solr.xml
   [junit4]   2> 466536 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores
   [junit4]   2> 466537 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.CoreContainer New CoreContainer 1078821418
   [junit4]   2> 466537 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001]
   [junit4]   2> 466537 WARN  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/lib
   [junit4]   2> 466537 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] 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> 466538 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 466539 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 466539 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 466539 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38549/solr
   [junit4]   2> 466539 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 466539 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 466540 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 466542 INFO  (zkCallback-283-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@111beda name:ZooKeeperConnection 
Watcher:127.0.0.1:38549 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 466542 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 466542 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 466544 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 466546 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@67e4cca4 
name:ZooKeeperConnection Watcher:127.0.0.1:38549/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 466546 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 466547 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 466550 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 466553 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 466555 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 466556 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 466558 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 466560 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 466561 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 466563 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 466564 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 466565 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 466567 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 466569 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 466570 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 466570 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.LeaderElector Joined leadership election 
with path: 
/overseer_elect/election/95581383828504580-127.0.0.1:35328_la-n_0000000000
   [junit4]   2> 466571 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:35328_la
   [junit4]   2> 466571 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/leader
   [junit4]   2> 466572 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.Overseer Overseer 
(id=95581383828504580-127.0.0.1:35328_la-n_0000000000) starting
   [junit4]   2> 466574 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/queue-work
   [junit4]   2> 466579 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=30000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 466580 INFO  
(OverseerCollectionConfigSetProcessor-95581383828504580-127.0.0.1:35328_la-n_0000000000)
 [n:127.0.0.1:35328_la    ] o.a.s.c.OverseerTaskProcessor Process current queue 
of overseer operations
   [junit4]   2> 466580 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 466580 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:35328_la
   [junit4]   2> 466581 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:35328_la
   [junit4]   2> 466583 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 466583 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent 
state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - 
updating... (live nodes size: [0])
   [junit4]   2> 466583 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 466596 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 466596 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 466597 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.CorePropertiesLocator Looking for core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores
   [junit4]   2> 466598 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: 
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
collection=tmp_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 466598 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.CorePropertiesLocator Found core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1
   [junit4]   2> 466598 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.c.CorePropertiesLocator Found 1 core 
definitions
   [junit4]   2> 466599 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 466599 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 466599 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0
   [junit4]   2> 466599 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:35328_la    ] o.a.s.s.SolrDispatchFilter 
SolrDispatchFilter.init() done
   [junit4]   2> 466600 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] 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:35328/la";,
   [junit4]   2>   "node_name":"127.0.0.1:35328_la",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"tmp_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 466600 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 466601 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] 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:35328/la";,
   [junit4]   2>   "node_name":"127.0.0.1:35328_la",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"tmp_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 466601 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
tmp_collection
   [junit4]   2> 466601 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard2
   [junit4]   2> 466602 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] 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> 467600 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 467601 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:tmp_collection
   [junit4]   2> 467601 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
Creating collection in ZooKeeper:tmp_collection
   [junit4]   2> 467601 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
Looking for collection configName
   [junit4]   2> 467602 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
Only one config set found in zk - using it:conf1
   [junit4]   2> 467602 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.c.SolrZkClient 
makePath: /collections/tmp_collection
   [junit4]   2> 467603 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent 
state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - 
updating...
   [junit4]   2> 467603 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.c.ZkStateReader 
Load collection config from: [/collections/tmp_collection]
   [junit4]   2> 467604 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.c.ZkStateReader 
path=[/collections/tmp_collection] [configName]=[conf1] specified config exists 
in ZooKeeper
   [junit4]   2> 467604 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1'
   [junit4]   2> 467604 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 467604 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 467606 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 467614 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 467617 WARN  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
instead.
   [junit4]   2> 467618 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.1.0
   [junit4]   2> 467636 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.SolrConfig 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 467642 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 467718 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.s.IndexSchema 
default search field in schema is text
   [junit4]   2> 467719 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 467719 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 467722 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 467740 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_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-6.x/solr/build/solr-core/test/J0
   [junit4]   2> 467740 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
tmp_collection
   [junit4]   2> 467741 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 467741 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1/data/]
   [junit4]   2> 467741 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 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@3d304a0f
   [junit4]   2> 467741 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1/data
   [junit4]   2> 467742 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 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-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1/data/index/
   [junit4]   2> 467742 WARN  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 467742 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1/data/index
   [junit4]   2> 467742 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=8, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=92.9912109375, 
floorSegmentMB=2.1640625, forceMergeDeletesPctAllowed=26.847327271450503, 
segmentsPerTier=4.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 467743 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@6d4c6a10 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@98ba223),segFN=segments_1,generation=1}
   [junit4]   2> 467743 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 467743 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:35328_la 
c:tmp_collection s:shard2 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-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1/data/
   [junit4]   2> 467743 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:35328_la 
c:tmp_collection s:shard2 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-001/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 467747 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"cdcr-processor-chain"
   [junit4]   2> 467762 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating 
implicit default
   [junit4]   2> 467764 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 467765 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 467766 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 467767 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 467780 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/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/ping,/admin/threads,/cdcr,/update,/admin/file
   [junit4]   2> 467780 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 467791 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 467791 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 467792 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 467792 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 467792 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.23108715385586126]
   [junit4]   2> 467793 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@6d4c6a10 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@98ba223),segFN=segments_1,generation=1}
   [junit4]   2> 467793 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 467793 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@55eb2567[collection1] main]
   [junit4]   2> 467793 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config from: 
[/collections/tmp_collection]
   [junit4]   2> 467794 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.c.ZkStateReader path=[/collections/tmp_collection] [configName]=[conf1] 
specified config exists in ZooKeeper
   [junit4]   2> 467794 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the 
RestManager with znodeBase: /configs/conf1
   [junit4]   2> 467794 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 467794 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 467794 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 467795 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage No data found for znode 
/configs/conf1/_rest_managed.json
   [junit4]   2> 467795 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 467795 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 467795 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 467799 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: /collections/tmp_collection/cdcr/state
   [junit4]   2> 467802 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.h.CdcrBufferStateManager Created znode 
/collections/tmp_collection/cdcr/state/buffer
   [junit4]   2> 467807 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.h.CdcrProcessStateManager Created znode 
/collections/tmp_collection/cdcr/state/process
   [junit4]   2> 467824 INFO  
(searcherExecutor-1122-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered 
new searcher Searcher@55eb2567[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 467824 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 467824 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version 
buckets from index
   [junit4]   2> 467824 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 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> 467824 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1529302142904434688
   [junit4]   2> 467826 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 
1529302142904434688
   [junit4]   2> 467826 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 467827 INFO  
(coreLoadExecutor-1121-thread-1-processing-n:127.0.0.1:35328_la) 
[n:127.0.0.1:35328_la c:tmp_collection s:shard2 r:core_node1 x:collection1] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 467827 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - 
core:collection1 address:http://127.0.0.1:35328/la collection:tmp_collection 
shard:shard2
   [junit4]   2> 467828 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: 
/collections/tmp_collection/leader_elect/shard2/election
   [junit4]   2> 467831 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership 
election with path: 
/collections/tmp_collection/leader_elect/shard2/election/95581383828504580-core_node1-n_0000000000
   [junit4]   2> 467831 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running 
the leader process for shard=shard2 and weAreReplacement=false and 
leaderVoteWait=10000
   [junit4]   2> 467833 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough 
replicas found to continue.
   [junit4]   2> 467833 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may 
be the new leader - try and sync
   [junit4]   2> 467833 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"tmp_collection"} current state version: 1
   [junit4]   2> 467833 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:35328/la/collection1/
   [junit4]   2> 467833 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now 
sync replicas to me
   [junit4]   2> 467833 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:35328/la/collection1/ has no replicas
   [junit4]   2> 467834 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: 
/collections/tmp_collection/leaders/shard2
   [junit4]   2> 467837 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase 
Creating leader registration node 
/collections/tmp_collection/leaders/shard2/leader after winning as 
/collections/tmp_collection/leader_elect/shard2/election/95581383828504580-core_node1-n_0000000000
   [junit4]   2> 467837 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] o.a.s.h.CdcrLeaderStateManager Received new leader state @ 
tmp_collection:shard2
   [junit4]   2> 467840 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am 
the new leader: http://127.0.0.1:35328/la/collection1/ shard2
   [junit4]   2> 467841 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"tmp_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:35328/la";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 467943 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] 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> 467992 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController We are 
http://127.0.0.1:35328/la/collection1/ and leader is 
http://127.0.0.1:35328/la/collection1/
   [junit4]   2> 467992 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed 
for core=collection1 baseURL=http://127.0.0.1:35328/la
   [junit4]   2> 467992 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no 
recovery necessary
   [junit4]   2> 467992 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController publishing 
state=active
   [junit4]   2> 467992 INFO  
(coreZkRegister-1115-thread-1-processing-n:127.0.0.1:35328_la x:collection1 
s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:35328_la c:tmp_collection 
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found 
on descriptor - reading it from system property
   [junit4]   2> 467995 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] 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:35328/la";,
   [junit4]   2>   "node_name":"127.0.0.1:35328_la",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"tmp_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 467995 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 
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:35328/la";,
   [junit4]   2>   "node_name":"127.0.0.1:35328_la",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"tmp_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 468099 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] 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> 468201 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/cores/collection1
   [junit4]   2> 468209 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 468211 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7886f16b{/la,null,AVAILABLE}
   [junit4]   2> 468213 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@549b61b8{HTTP/1.1,[http/1.1]}{127.0.0.1:41326}
   [junit4]   2> 468214 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.e.j.s.Server Started @471378ms
   [junit4]   2> 468214 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard2, 
hostContext=/la, hostPort=41326, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/cores}
   [junit4]   2> 468216 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 468216 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002'
   [junit4]   2> 468216 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr 
(NoInitialContextEx)
   [junit4]   2> 468216 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not 
find system property or JNDI)
   [junit4]   2> 468216 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 468218 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 468220 INFO  (zkCallback-285-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5a9899cf 
name:ZooKeeperConnection Watcher:127.0.0.1:38549/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 468220 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 468220 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 468222 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 468222 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/solr.xml
   [junit4]   2> 468232 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/cores
   [junit4]   2> 468233 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.CoreContainer New CoreContainer 715281637
   [junit4]   2> 468233 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002]
   [junit4]   2> 468233 WARN  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/lib
   [junit4]   2> 468233 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] 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> 468234 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 468235 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 468235 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 468235 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38549/solr
   [junit4]   2> 468235 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 468235 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 468236 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 468237 INFO  (zkCallback-288-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1c2d71b5 
name:ZooKeeperConnection Watcher:127.0.0.1:38549 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 468238 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 468238 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 468245 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 468246 INFO  
(zkCallback-289-thread-1-processing-n:127.0.0.1:41326_la) [n:127.0.0.1:41326_la 
   ] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@74decb37 
name:ZooKeeperConnection Watcher:127.0.0.1:38549/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 468249 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 468256 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 468257 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 468259 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 468260 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.LeaderElector Joined leadership election 
with path: 
/overseer_elect/election/95581383828504583-127.0.0.1:41326_la-n_0000000001
   [junit4]   2> 468261 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.LeaderElector Watching path 
/overseer_elect/election/95581383828504580-127.0.0.1:35328_la-n_0000000000 to 
know if I could be the leader
   [junit4]   2> 468261 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:41326_la as DOWN
   [junit4]   2> 468263 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:41326_la"} current state version: 3
   [junit4]   2> 468263 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.o.NodeMutator DownNode state invoked for 
node: 127.0.0.1:41326_la
   [junit4]   2> 468365 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] 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> 468365 INFO  
(zkCallback-289-thread-1-processing-n:127.0.0.1:41326_la) [n:127.0.0.1:41326_la 
   ] 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> 469264 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41326_la
   [junit4]   2> 469264 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:41326_la
   [junit4]   2> 469265 INFO  
(zkCallback-289-thread-1-processing-n:127.0.0.1:41326_la) [n:127.0.0.1:41326_la 
   ] 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> 469265 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] 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> 469278 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 469278 INFO  
(zkCallback-289-thread-1-processing-n:127.0.0.1:41326_la) [n:127.0.0.1:41326_la 
   ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 469281 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 469281 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 469281 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.CorePropertiesLocator Looking for core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/cores
   [junit4]   2> 469282 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: 
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
collection=tmp_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 469282 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.CorePropertiesLocator Found core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/cores/collection1
   [junit4]   2> 469283 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.c.CorePropertiesLocator Found 1 core 
definitions
   [junit4]   2> 469291 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 469291 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 469291 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0
   [junit4]   2> 469291 INFO  
(TEST-CdcrReplicationDistributedZkTest.testTargetCollectionNotAvailable-seed#[7FFD6E506C30D409])
 [n:127.0.0.1:41326_la    ] o.a.s.s.SolrDispatchFilter 
SolrDispatchFilter.init() done
   [junit4]   2> 469292 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
Registering watch for collection tmp_collection
   [junit4]   2> 469292 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.c.ZkStateReader 
addZkWatch [tmp_collection]
   [junit4]   2> 469292 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.c.ZkStateReader 
Deleting data for [tmp_collection]
   [junit4]   2> 469292 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] 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:41326/la";,
   [junit4]   2>   "node_name":"127.0.0.1:41326_la",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"tmp_collection",
   [junit4]   2>   "operation":"state"} current state version: 4
   [junit4]   2> 469293 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] 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:41326/la";,
   [junit4]   2>   "node_name":"127.0.0.1:41326_la",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"tmp_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 469293 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.o.ReplicaMutator Collection already exists 
with numShards=2
   [junit4]   2> 469293 INFO  
(OverseerStateUpdate-95581383828504580-127.0.0.1:35328_la-n_0000000000) 
[n:127.0.0.1:35328_la    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 469294 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 469395 INFO  
(zkCallback-289-thread-1-processing-n:127.0.0.1:41326_la) [n:127.0.0.1:41326_la 
   ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [2])
   [junit4]   2> 469395 INFO  
(zkCallback-284-thread-1-processing-n:127.0.0.1:35328_la) [n:127.0.0.1:35328_la 
   ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [2])
   [junit4]   2> 470294 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 470294 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:tmp_collection
   [junit4]   2> 470294 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 470294 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.c.ZkStateReader 
Load collection config from: [/collections/tmp_collection]
   [junit4]   2> 470295 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.c.ZkStateReader 
path=[/collections/tmp_collection] [configName]=[conf1] specified config exists 
in ZooKeeper
   [junit4]   2> 470295 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrReplicationDistributedZkTest_7FFD6E506C30D409-001/jetty-002/cores/collection1'
   [junit4]   2> 470295 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 470295 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 470297 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 470302 INFO  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 470324 WARN  
(coreLoadExecutor-1133-thread-1-processing-n:127.0.0.1:41326_la) 
[n:127.0.0.1:41326_la c:tmp_collection   x:collection1] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
ins

[...truncated too long message...]

E: 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=UnloadDistributedZkTest -Dtests.method=test 
-Dtests.seed=7FFD6E506C30D409 -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=fr-CH -Dtests.timezone=Pacific/Majuro -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR    138s J2 | UnloadDistributedZkTest.test <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=19519, name=testExecutor-4520-thread-11, 
state=RUNNABLE, group=TGRP-UnloadDistributedZkTest]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([7FFD6E506C30D409:F7A9518AC2CCB9F1]:0)
   [junit4]    > Caused by: java.lang.RuntimeException: 
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting 
response from server at: http://127.0.0.1:43802/tz/zi
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([7FFD6E506C30D409]:0)
   [junit4]    >        at 
org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$0(BasicDistributedZkTest.java:583)
   [junit4]    >        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]    > Caused by: org.apache.solr.client.solrj.SolrServerException: 
Timeout occured while waiting response from server at: 
http://127.0.0.1:43802/tz/zi
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]    >        at 
org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$0(BasicDistributedZkTest.java:581)
   [junit4]    >        ... 4 more
   [junit4]    > Caused by: java.net.SocketTimeoutException: Read timed out
   [junit4]    >        at java.net.SocketInputStream.socketRead0(Native Method)
   [junit4]    >        at 
java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
   [junit4]    >        at 
java.net.SocketInputStream.read(SocketInputStream.java:170)
   [junit4]    >        at 
java.net.SocketInputStream.read(SocketInputStream.java:141)
   [junit4]    >        at 
org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
   [junit4]    >        at 
org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
   [junit4]    >        at 
org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
   [junit4]    >        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
   [junit4]    >        at 
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
   [junit4]    >        at 
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
   [junit4]    >        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
   [junit4]    >        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
   [junit4]    >        at 
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
   [junit4]    >        at 
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
   [junit4]    >        at 
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482)
   [junit4]    >        ... 8 more
   [junit4]   2> 1596724 INFO  
(SUITE-UnloadDistributedZkTest-seed#[7FFD6E506C30D409]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Mar 20, 2016 7:01:44 AM 
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=BlockTreeOrds(blocksize=128), 
range_facet_l_dv=PostingsFormat(name=Asserting), 
_version_=BlockTreeOrds(blocksize=128), 
multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_t=BlockTreeOrds(blocksize=128), intDefault=BlockTreeOrds(blocksize=128), 
a_i1=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=Asserting), 
range_facet_i_dv=BlockTreeOrds(blocksize=128), 
text=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
range_facet_l=BlockTreeOrds(blocksize=128), 
timestamp=BlockTreeOrds(blocksize=128)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), 
range_facet_i_dv=DocValuesFormat(name=Asserting), 
timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1994, 
maxMBSortInHeap=5.969688430660409, 
sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=fr-CH, 
timezone=Pacific/Majuro
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_74 (64-bit)/cpus=4,threads=1,free=226887016,total=520093696
   [junit4]   2> NOTE: All tests run in this JVM: [TestWriterPerf, 
TestCSVResponseWriter, RulesTest, HdfsRestartWhileUpdatingTest, 
HighlighterConfigTest, TestRandomCollapseQParserPlugin, 
SpatialRPTFieldTypeTest, ClassificationUpdateProcessorFactoryTest, 
TestSolrQueryResponse, CleanupOldIndexTest, TestReloadAndDeleteDocs, 
TestJsonRequest, TestRecovery, CurrencyFieldXmlFileTest, MultiThreadedOCPTest, 
BadComponentTest, TestSolrQueryParser, HdfsChaosMonkeyNothingIsSafeTest, 
BlockJoinFacetDistribTest, TestDefaultStatsCache, HdfsDirectoryTest, TestSolrJ, 
CircularListTest, VersionInfoTest, TestConfigOverlay, DateRangeFieldTest, 
SimpleFacetsTest, TestUseDocValuesAsStored2, HighlighterMaxOffsetTest, 
TestCoreDiscovery, TestPivotHelperCode, TestIndexSearcher, 
TestIndexingPerformance, TestCloudDeleteByQuery, TestPerFieldSimilarity, 
TestPerFieldSimilarityClassic, OverseerTaskQueueTest, HdfsNNFailoverTest, 
TestExactStatsCache, TestDocBasedVersionConstraints, TestStressReorder, 
TestSchemaNameResource, TestObjectReleaseTracker, 
SharedFSAutoReplicaFailoverTest, CollectionStateFormat2Test, 
TestSuggestSpellingConverter, TestDynamicFieldCollectionResource, 
TestSolrDynamicMBean, TestReload, TestReversedWildcardFilterFactory, 
PrimitiveFieldTypeTest, TestRuleBasedAuthorizationPlugin, 
DistributedExpandComponentTest, DeleteLastCustomShardedReplicaTest, 
QueryParsingTest, TermVectorComponentDistributedTest, DebugComponentTest, 
TestSimpleQParserPlugin, GraphQueryTest, ScriptEngineTest, 
TestCollapseQParserPlugin, SolrTestCaseJ4Test, ClusterStateTest, TestMacros, 
TestJmxIntegration, ReplicationFactorTest, 
StatelessScriptUpdateProcessorFactoryTest, ConjunctionSolrSpellCheckerTest, 
AnalysisErrorHandlingTest, FacetPivotSmallTest, NoCacheHeaderTest, 
TestExtendedDismaxParser, BlockCacheTest, TestNamedUpdateProcessors, 
TestDownShardTolerantSearch, RegexBoostProcessorTest, RecoveryZkTest, 
RequestHandlersTest, TestConfigSetsAPIZkFailure, TestJettySolrRunner, 
TestRequestStatusCollectionAPI, LeaderInitiatedRecoveryOnCommitTest, 
SolrCoreCheckLockOnStartupTest, LeaderElectionTest, TestManagedResourceStorage, 
DirectSolrConnectionTest, TestCharFilters, TestZkChroot, TestInitQParser, 
OverseerTest, TestFileDictionaryLookup, TestSweetSpotSimilarityFactory, 
BasicDistributedZk2Test, UnloadDistributedZkTest]
   [junit4] Completed [200/581 (2!)] on J2 in 138.59s, 1 test, 1 error <<< 
FAILURES!

[...truncated 1216 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014:
 There were test failures: 581 suites (10 ignored), 2327 tests, 1 error, 1 
failure, 32 ignored (16 assumptions) [seed: 7FFD6E506C30D409]

Total time: 203 minutes 28 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: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to