Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/700/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC

1 tests failed.
FAILED:  org.apache.solr.cloud.ShardSplitTest.test

Error Message:
java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 
127.0.0.1:41903/solr within 10000 ms

Stack Trace:
org.apache.solr.common.SolrException: java.util.concurrent.TimeoutException: 
Could not connect to ZooKeeper 127.0.0.1:41903/solr within 10000 ms
        at 
__randomizedtesting.SeedInfo.seed([A156A4036A7169CC:29029BD9C48D0434]:0)
        at 
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:182)
        at 
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:116)
        at 
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:106)
        at 
org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:226)
        at 
org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:121)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:615)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.initCloud(AbstractFullDistribZkTestBase.java:268)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:330)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:990)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        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:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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:368)
        at java.lang.Thread.run(Thread.java:745)
Caused by: java.util.concurrent.TimeoutException: Could not connect to 
ZooKeeper 127.0.0.1:41903/solr within 10000 ms
        at 
org.apache.solr.common.cloud.ConnectionManager.waitForConnected(ConnectionManager.java:233)
        at 
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:174)
        ... 40 more




Build Log:
[...truncated 12576 lines...]
   [junit4] Suite: org.apache.solr.cloud.ShardSplitTest
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/init-core-data-001
   [junit4]   2> 2046483 INFO  
(SUITE-ShardSplitTest-seed#[A156A4036A7169CC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 2046484 INFO  
(SUITE-ShardSplitTest-seed#[A156A4036A7169CC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
   [junit4]   2> 2046484 INFO  
(SUITE-ShardSplitTest-seed#[A156A4036A7169CC]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2046487 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2046487 INFO  (Thread-4676) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2046487 INFO  (Thread-4676) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2046587 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.ZkTestServer start zk server on port:61063
   [junit4]   2> 2046606 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 2046610 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/schema15.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 2046612 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2046615 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 2046617 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 2046619 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 2046622 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2046624 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2046627 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2046629 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2046632 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractZkTestCase put 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 2046782 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/control-001/cores/collection1
   [junit4]   2> 2046784 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2046785 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@b2d53fc{/,null,AVAILABLE}
   [junit4]   2> 2046785 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.AbstractConnector Started 
ServerConnector@691ee6be{HTTP/1.1,[http/1.1]}{127.0.0.1:64439}
   [junit4]   2> 2046786 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server Started @2051618ms
   [junit4]   2> 2046786 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/tempDir-001/control/data,
 hostContext=/, hostPort=64439, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/control-001/cores}
   [junit4]   2> 2046786 ERROR 
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may 
be missing or incomplete.
   [junit4]   2> 2046786 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 6.5.0
   [junit4]   2> 2046786 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 2046786 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2046786 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-03T15:58:28.170Z
   [junit4]   2> 2046790 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2046790 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/control-001/solr.xml
   [junit4]   2> 2046797 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2046797 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61063/solr
   [junit4]   2> 2046855 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:64439_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2046856 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:64439_    ] o.a.s.c.OverseerElectionContext I am going to be the 
leader 127.0.0.1:64439_
   [junit4]   2> 2046858 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:64439_    ] o.a.s.c.Overseer Overseer 
(id=97554052407164932-127.0.0.1:64439_-n_0000000000) starting
   [junit4]   2> 2046870 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:64439_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64439_
   [junit4]   2> 2046873 INFO  
(zkCallback-3975-thread-1-processing-n:127.0.0.1:64439_) [n:127.0.0.1:64439_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2046982 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:64439_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions 
underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/control-001/cores
   [junit4]   2> 2046982 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:64439_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2046986 INFO  
(OverseerStateUpdate-97554052407164932-127.0.0.1:64439_-n_0000000000) 
[n:127.0.0.1:64439_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 2048001 WARN  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
instead.
   [junit4]   2> 2048002 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2048014 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 2048073 WARN  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] default search field in schema is text. WARNING: Deprecated, 
please use 'df' on request instead.
   [junit4]   2> 2048074 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 2048098 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
control_collection
   [junit4]   2> 2048099 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.c.SolrCore 
[[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/control-001/cores/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/control-001/cores/collection1/data/]
   [junit4]   2> 2048099 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@71a0f248
   [junit4]   2> 2048100 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=97.2216796875, 
floorSegmentMB=1.6044921875, forceMergeDeletesPctAllowed=0.541649612319347, 
segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.20092814711871407
   [junit4]   2> 2048110 WARN  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 2048152 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2048152 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2048152 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 2048152 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 2048153 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4379289027849109]
   [junit4]   2> 2048153 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3b602d4b[collection1] main]
   [junit4]   2> 2048155 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2048156 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2048156 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2048157 INFO  
(searcherExecutor-8816-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection   x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3b602d4b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2048158 INFO  
(coreLoadExecutor-8815-thread-1-processing-n:127.0.0.1:64439_) 
[n:127.0.0.1:64439_ c:control_collection   x:collection1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560864840264712192
   [junit4]   2> 2048182 INFO  
(coreZkRegister-8808-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas 
found to continue.
   [junit4]   2> 2048182 INFO  
(coreZkRegister-8808-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new 
leader - try and sync
   [junit4]   2> 2048182 INFO  
(coreZkRegister-8808-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:64439/collection1/
   [junit4]   2> 2048182 INFO  
(coreZkRegister-8808-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync 
replicas to me
   [junit4]   2> 2048182 INFO  
(coreZkRegister-8808-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:64439/collection1/ has no replicas
   [junit4]   2> 2048182 INFO  
(coreZkRegister-8808-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all 
replicas participating in election, clear LIR
   [junit4]   2> 2048192 INFO  
(coreZkRegister-8808-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new 
leader: http://127.0.0.1:64439/collection1/ shard1
   [junit4]   2> 2048344 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2048345 INFO  
(coreZkRegister-8808-thread-1-processing-n:127.0.0.1:64439_ x:collection1 
c:control_collection) [n:127.0.0.1:64439_ c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery 
necessary
   [junit4]   2> 2048348 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61063/solr 
ready
   [junit4]   2> 2048348 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 2048348 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with 
stateFormat=2
   [junit4]   2> 2048503 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-1-001/cores/collection1
   [junit4]   2> 2048503 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-1-001
   [junit4]   2> 2048504 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2048506 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@424e94b9{/,null,AVAILABLE}
   [junit4]   2> 2048507 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.AbstractConnector Started 
ServerConnector@24f39390{HTTP/1.1,[http/1.1]}{127.0.0.1:55055}
   [junit4]   2> 2048507 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server Started @2053340ms
   [junit4]   2> 2048507 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/tempDir-001/jetty1,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=55055, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-1-001/cores}
   [junit4]   2> 2048507 ERROR 
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may 
be missing or incomplete.
   [junit4]   2> 2048507 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 6.5.0
   [junit4]   2> 2048507 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 2048508 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2048508 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-03T15:58:29.892Z
   [junit4]   2> 2048512 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2048512 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-1-001/solr.xml
   [junit4]   2> 2048517 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2048518 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61063/solr
   [junit4]   2> 2048535 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:55055_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2048539 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:55055_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2048543 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:55055_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:55055_
   [junit4]   2> 2048546 INFO  
(zkCallback-3975-thread-3-processing-n:127.0.0.1:64439_) [n:127.0.0.1:64439_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2048546 INFO  (zkCallback-3979-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2048547 INFO  
(zkCallback-3985-thread-1-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2048637 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:55055_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions 
underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-1-001/cores
   [junit4]   2> 2048637 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:55055_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2048641 INFO  
(OverseerStateUpdate-97554052407164932-127.0.0.1:64439_-n_0000000000) 
[n:127.0.0.1:64439_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard2
   [junit4]   2> 2048746 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 2049674 WARN  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.c.Config Beginning 
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2049675 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 2049688 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 2049769 WARN  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] default search field in schema is text. WARNING: Deprecated, 
please use 'df' on request instead.
   [junit4]   2> 2049772 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded 
schema test/1.6 with uniqueid field id
   [junit4]   2> 2049803 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 2049804 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.c.SolrCore 
[[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-1-001/cores/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-1-001/cores/collection1/data/]
   [junit4]   2> 2049804 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.c.JmxMonitoredMap JMX 
monitoring is enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@71a0f248
   [junit4]   2> 2049806 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: 
[TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=15, 
maxMergedSegmentMB=97.2216796875, floorSegmentMB=1.6044921875, 
forceMergeDeletesPctAllowed=0.541649612319347, segmentsPerTier=15.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20092814711871407
   [junit4]   2> 2049817 WARN  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.c.RequestHandlers 
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class 
= DumpRequestHandler,attributes = {initParams=a, name=/dump, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 2049865 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2049865 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2049866 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2049866 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2049866 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4379289027849109]
   [junit4]   2> 2049867 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@22dcde13[collection1] main]
   [junit4]   2> 2049868 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2049869 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2049869 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2049870 INFO  
(searcherExecutor-8827-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1   x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@22dcde13[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2049870 INFO  
(coreLoadExecutor-8826-thread-1-processing-n:127.0.0.1:55055_) 
[n:127.0.0.1:55055_ c:collection1   x:collection1] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1560864842059874304
   [junit4]   2> 2049880 INFO  
(coreZkRegister-8821-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to 
continue.
   [junit4]   2> 2049880 INFO  
(coreZkRegister-8821-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try 
and sync
   [junit4]   2> 2049880 INFO  
(coreZkRegister-8821-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:55055/collection1/
   [junit4]   2> 2049880 INFO  
(coreZkRegister-8821-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2049880 INFO  
(coreZkRegister-8821-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:55055/collection1/ has no 
replicas
   [junit4]   2> 2049880 INFO  
(coreZkRegister-8821-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas 
participating in election, clear LIR
   [junit4]   2> 2049885 INFO  
(coreZkRegister-8821-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:55055/collection1/ shard2
   [junit4]   2> 2049992 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 2050037 INFO  
(coreZkRegister-8821-thread-1-processing-n:127.0.0.1:55055_ x:collection1 
c:collection1) [n:127.0.0.1:55055_ c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2050145 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 2050479 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-2-001/cores/collection1
   [junit4]   2> 2050480 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-2-001
   [junit4]   2> 2050480 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2050482 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2de40270{/,null,AVAILABLE}
   [junit4]   2> 2050483 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.AbstractConnector Started 
ServerConnector@256ded03{HTTP/1.1,[http/1.1]}{127.0.0.1:41332}
   [junit4]   2> 2050483 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server Started @2055317ms
   [junit4]   2> 2050483 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/tempDir-001/jetty2,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=41332, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-2-001/cores}
   [junit4]   2> 2050483 ERROR 
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may 
be missing or incomplete.
   [junit4]   2> 2050483 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 6.5.0
   [junit4]   2> 2050483 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 2050483 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2050483 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-03T15:58:31.867Z
   [junit4]   2> 2050487 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2050487 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-2-001/solr.xml
   [junit4]   2> 2050493 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2050493 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61063/solr
   [junit4]   2> 2050509 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:41332_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2050514 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:41332_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2050517 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:41332_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41332_
   [junit4]   2> 2050537 INFO  
(zkCallback-3975-thread-3-processing-n:127.0.0.1:64439_) [n:127.0.0.1:64439_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2050537 INFO  (zkCallback-3979-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2050537 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2050539 INFO  
(zkCallback-3991-thread-1-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2050608 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:41332_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions 
underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-2-001/cores
   [junit4]   2> 2050608 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:41332_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2050611 INFO  
(OverseerStateUpdate-97554052407164932-127.0.0.1:64439_-n_0000000000) 
[n:127.0.0.1:64439_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 2050716 INFO  
(zkCallback-3985-thread-1-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 2050716 INFO  
(zkCallback-3991-thread-2-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 2051627 WARN  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.c.Config Beginning 
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2051628 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 2051655 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 2051722 WARN  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] default search field in schema is text. WARNING: Deprecated, 
please use 'df' on request instead.
   [junit4]   2> 2051723 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded 
schema test/1.6 with uniqueid field id
   [junit4]   2> 2051748 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 2051748 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.c.SolrCore 
[[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-2-001/cores/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-2-001/cores/collection1/data/]
   [junit4]   2> 2051748 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.c.JmxMonitoredMap JMX 
monitoring is enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@71a0f248
   [junit4]   2> 2051750 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: 
[TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=15, 
maxMergedSegmentMB=97.2216796875, floorSegmentMB=1.6044921875, 
forceMergeDeletesPctAllowed=0.541649612319347, segmentsPerTier=15.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20092814711871407
   [junit4]   2> 2051760 WARN  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.c.RequestHandlers 
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class 
= DumpRequestHandler,attributes = {initParams=a, name=/dump, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 2051803 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2051803 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2051804 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2051804 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2051804 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4379289027849109]
   [junit4]   2> 2051805 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@341e6d73[collection1] main]
   [junit4]   2> 2051807 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2051808 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2051808 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2051809 INFO  
(searcherExecutor-8838-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1   x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@341e6d73[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2051809 INFO  
(coreLoadExecutor-8837-thread-1-processing-n:127.0.0.1:41332_) 
[n:127.0.0.1:41332_ c:collection1   x:collection1] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1560864844093063168
   [junit4]   2> 2051819 INFO  
(coreZkRegister-8832-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to 
continue.
   [junit4]   2> 2051820 INFO  
(coreZkRegister-8832-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try 
and sync
   [junit4]   2> 2051820 INFO  
(coreZkRegister-8832-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:41332/collection1/
   [junit4]   2> 2051820 INFO  
(coreZkRegister-8832-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2051820 INFO  
(coreZkRegister-8832-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:41332/collection1/ has no 
replicas
   [junit4]   2> 2051821 INFO  
(coreZkRegister-8832-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas 
participating in election, clear LIR
   [junit4]   2> 2051827 INFO  
(coreZkRegister-8832-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:41332/collection1/ shard1
   [junit4]   2> 2051931 INFO  
(zkCallback-3991-thread-2-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 2051931 INFO  
(zkCallback-3985-thread-1-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 2051967 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-3-001/cores/collection1
   [junit4]   2> 2051968 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-3-001
   [junit4]   2> 2051968 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2051970 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6f4a3a0a{/,null,AVAILABLE}
   [junit4]   2> 2051971 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.AbstractConnector Started 
ServerConnector@7df8a61d{HTTP/1.1,[http/1.1]}{127.0.0.1:37361}
   [junit4]   2> 2051971 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server Started @2056806ms
   [junit4]   2> 2051971 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/tempDir-001/jetty3,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=37361, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-3-001/cores}
   [junit4]   2> 2051971 ERROR 
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may 
be missing or incomplete.
   [junit4]   2> 2051971 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 6.5.0
   [junit4]   2> 2051971 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 2051971 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2051971 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-03T15:58:33.355Z
   [junit4]   2> 2051976 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2051976 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-3-001/solr.xml
   [junit4]   2> 2051978 INFO  
(coreZkRegister-8832-thread-1-processing-n:127.0.0.1:41332_ x:collection1 
c:collection1) [n:127.0.0.1:41332_ c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2051983 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2051984 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61063/solr
   [junit4]   2> 2051999 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:37361_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 2052003 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:37361_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2052007 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:37361_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37361_
   [junit4]   2> 2052009 INFO  
(zkCallback-3991-thread-2-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 2052009 INFO  
(zkCallback-3985-thread-1-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 2052010 INFO  (zkCallback-3979-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2052010 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2052010 INFO  
(zkCallback-3991-thread-1-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2052011 INFO  
(zkCallback-3975-thread-2-processing-n:127.0.0.1:64439_) [n:127.0.0.1:64439_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2052012 INFO  
(zkCallback-3997-thread-1-processing-n:127.0.0.1:37361_) [n:127.0.0.1:37361_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2052120 WARN  
(OverseerStateUpdate-97554052407164932-127.0.0.1:64439_-n_0000000000) 
[n:127.0.0.1:64439_    ] o.a.s.c.Overseer Bad version writing to ZK using 
compare-and-set, will force refresh cluster state: KeeperErrorCode = BadVersion 
for /collections/collection1/state.json
   [junit4]   2> 2052127 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2052127 INFO  
(zkCallback-3991-thread-1-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2052172 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:37361_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions 
underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-3-001/cores
   [junit4]   2> 2052172 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:37361_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2052175 INFO  
(OverseerStateUpdate-97554052407164932-127.0.0.1:64439_-n_0000000000) 
[n:127.0.0.1:64439_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard2
   [junit4]   2> 2052279 INFO  
(zkCallback-3991-thread-1-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2052279 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2052279 INFO  
(zkCallback-3997-thread-1-processing-n:127.0.0.1:37361_) [n:127.0.0.1:37361_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2053191 WARN  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.c.Config Beginning 
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2053192 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 2053204 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 2053289 WARN  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] default search field in schema is text. WARNING: Deprecated, 
please use 'df' on request instead.
   [junit4]   2> 2053290 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded 
schema test/1.6 with uniqueid field id
   [junit4]   2> 2053318 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 2053318 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.c.SolrCore 
[[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-3-001/cores/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-3-001/cores/collection1/data/]
   [junit4]   2> 2053318 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.c.JmxMonitoredMap JMX 
monitoring is enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@71a0f248
   [junit4]   2> 2053321 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: 
[TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=15, 
maxMergedSegmentMB=97.2216796875, floorSegmentMB=1.6044921875, 
forceMergeDeletesPctAllowed=0.541649612319347, segmentsPerTier=15.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20092814711871407
   [junit4]   2> 2053330 WARN  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.c.RequestHandlers 
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class 
= DumpRequestHandler,attributes = {initParams=a, name=/dump, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 2053380 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2053380 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2053381 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2053381 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2053381 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4379289027849109]
   [junit4]   2> 2053382 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@282143f5[collection1] main]
   [junit4]   2> 2053383 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 2053384 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 2053384 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2053385 INFO  
(searcherExecutor-8849-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
c:collection1) [n:127.0.0.1:37361_ c:collection1   x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@282143f5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2053385 INFO  
(coreLoadExecutor-8848-thread-1-processing-n:127.0.0.1:37361_) 
[n:127.0.0.1:37361_ c:collection1   x:collection1] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1560864845745618944
   [junit4]   2> 2053391 INFO  
(coreZkRegister-8843-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
c:collection1) [n:127.0.0.1:37361_ c:collection1 s:shard2 r:core_node3 
x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
   [junit4]   2> 2053391 INFO  
(updateExecutor-3994-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 2053392 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process. 
recoveringAfterStartup=true
   [junit4]   2> 2053392 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
   [junit4]   2> 2053392 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates. 
core=[collection1]
   [junit4]   2> 2053393 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. 
FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 2053393 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core 
[collection1] as recovering, leader is [http://127.0.0.1:55055/collection1/] 
and I am [http://127.0.0.1:37361/collection1/]
   [junit4]   2> 2053397 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery 
command to [http://127.0.0.1:55055]; [WaitForState: 
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:37361_&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 2053398 INFO  (qtp193309910-20908) [n:127.0.0.1:55055_    ] 
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: 
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
   [junit4]   2> 2053399 INFO  (qtp193309910-20908) [n:127.0.0.1:55055_    ] 
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 
(shard2 of collection1) have state: recovering
   [junit4]   2> 2053399 INFO  (qtp193309910-20908) [n:127.0.0.1:55055_    ] 
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, 
shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? 
true, live=true, checkLive=true, currentState=down, localState=active, 
nodeName=127.0.0.1:37361_, coreNodeName=core_node3, 
onlyIfActiveCheckResult=false, nodeProps: 
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:37361","node_name":"127.0.0.1:37361_","state":"down"}
   [junit4]   2> 2053498 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2053498 INFO  
(zkCallback-3991-thread-1-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2053498 INFO  
(zkCallback-3997-thread-1-processing-n:127.0.0.1:37361_) [n:127.0.0.1:37361_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2053605 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-4-001/cores/collection1
   [junit4]   2> 2053606 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-4-001
   [junit4]   2> 2053606 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2053608 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4244dd83{/,null,AVAILABLE}
   [junit4]   2> 2053609 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.AbstractConnector Started 
ServerConnector@7de82761{HTTP/1.1,[http/1.1]}{127.0.0.1:60699}
   [junit4]   2> 2053609 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.e.j.s.Server Started @2058445ms
   [junit4]   2> 2053609 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/tempDir-001/jetty4,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=60699, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-4-001/cores}
   [junit4]   2> 2053609 ERROR 
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may 
be missing or incomplete.
   [junit4]   2> 2053609 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 6.5.0
   [junit4]   2> 2053609 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 2053609 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2053610 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-03T15:58:34.994Z
   [junit4]   2> 2053613 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 2053613 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-4-001/solr.xml
   [junit4]   2> 2053620 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2053621 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) [ 
   ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61063/solr
   [junit4]   2> 2053637 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:60699_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 2053641 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:60699_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2053645 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:60699_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:60699_
   [junit4]   2> 2053647 INFO  
(zkCallback-3975-thread-2-processing-n:127.0.0.1:64439_) [n:127.0.0.1:64439_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2053648 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2053648 INFO  
(zkCallback-3991-thread-1-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2053648 INFO  (zkCallback-3979-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2053649 INFO  
(zkCallback-3997-thread-1-processing-n:127.0.0.1:37361_) [n:127.0.0.1:37361_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2053649 INFO  
(zkCallback-4004-thread-1-processing-n:127.0.0.1:60699_) [n:127.0.0.1:60699_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2053754 INFO  
(zkCallback-3991-thread-1-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 2053755 INFO  
(zkCallback-3997-thread-2-processing-n:127.0.0.1:37361_) [n:127.0.0.1:37361_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 2053755 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 2053764 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:60699_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions 
underneath 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-4-001/cores
   [junit4]   2> 2053764 INFO  
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[A156A4036A7169CC]) 
[n:127.0.0.1:60699_    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2053770 INFO  
(OverseerStateUpdate-97554052407164932-127.0.0.1:64439_-n_0000000000) 
[n:127.0.0.1:64439_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 2053897 INFO  
(zkCallback-3997-thread-2-processing-n:127.0.0.1:37361_) [n:127.0.0.1:37361_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 2053897 INFO  
(zkCallback-3991-thread-1-processing-n:127.0.0.1:41332_) [n:127.0.0.1:41332_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 2053897 INFO  
(zkCallback-3985-thread-2-processing-n:127.0.0.1:55055_) [n:127.0.0.1:55055_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 2053898 INFO  
(zkCallback-4004-thread-2-processing-n:127.0.0.1:60699_) [n:127.0.0.1:60699_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 2054400 INFO  (qtp193309910-20908) [n:127.0.0.1:55055_    ] 
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, 
shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? 
true, live=true, checkLive=true, currentState=recovering, localState=active, 
nodeName=127.0.0.1:37361_, coreNodeName=core_node3, 
onlyIfActiveCheckResult=false, nodeProps: 
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:37361","node_name":"127.0.0.1:37361_","state":"recovering"}
   [junit4]   2> 2054400 INFO  (qtp193309910-20908) [n:127.0.0.1:55055_    ] 
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering, 
checkLive: true, onlyIfLeader: true for: 1 seconds.
   [junit4]   2> 2054400 INFO  (qtp193309910-20908) [n:127.0.0.1:55055_    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={nodeName=127.0.0.1:37361_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
 status=0 QTime=1002
   [junit4]   2> 2054789 WARN  
(coreLoadExecutor-8859-thread-1-processing-n:127.0.0.1:60699_) 
[n:127.0.0.1:60699_ c:collection1   x:collection1] o.a.s.c.Config Beginning 
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 2054790 INFO  
(coreLoadExecutor-8859-thread-1-processing-n:127.0.0.1:60699_) 
[n:127.0.0.1:60699_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 2054807 INFO  
(coreLoadExecutor-8859-thread-1-processing-n:127.0.0.1:60699_) 
[n:127.0.0.1:60699_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 2054895 WARN  
(coreLoadExecutor-8859-thread-1-processing-n:127.0.0.1:60699_) 
[n:127.0.0.1:60699_ c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] default search field in schema is text. WARNING: Deprecated, 
please use 'df' on request instead.
   [junit4]   2> 2054896 INFO  
(coreLoadExecutor-8859-thread-1-processing-n:127.0.0.1:60699_) 
[n:127.0.0.1:60699_ c:collection1   x:collection1] o.a.s.s.IndexSchema Loaded 
schema test/1.6 with uniqueid field id
   [junit4]   2> 2054902 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Attempting to PeerSync 
from [http://127.0.0.1:55055/collection1/] - recoveringAfterStartup=[true]
   [junit4]   2> 2054902 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.u.PeerSync PeerSync: core=collection1 
url=http://127.0.0.1:37361 START replicas=[http://127.0.0.1:55055/collection1/] 
nUpdates=100
   [junit4]   2> 2054906 INFO  (qtp193309910-20911) [n:127.0.0.1:55055_ 
c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.IndexFingerprint 
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807, 
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, 
maxDoc=0}
   [junit4]   2> 2054906 INFO  (qtp193309910-20911) [n:127.0.0.1:55055_ 
c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request 
[collection1]  webapp= path=/get 
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2054907 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.u.IndexFingerprint IndexFingerprint 
millis:0.0 result:{maxVersionSpecified=9223372036854775807, 
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, 
maxDoc=0}
   [junit4]   2> 2054907 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.u.PeerSync We are already in sync. No need to 
do a PeerSync 
   [junit4]   2> 2054907 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2054907 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted 
changes. Skipping IW.commit.
   [junit4]   2> 2054908 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2054908 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy PeerSync stage of recovery 
was successful.
   [junit4]   2> 2054908 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Replaying updates buffered 
during PeerSync.
   [junit4]   2> 2054909 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy No replay needed.
   [junit4]   2> 2054909 INFO  
(recoveryExecutor-3995-thread-1-processing-n:127.0.0.1:37361_ x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:37361_ c:collection1 s:shard2 
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Registering as Active 
after recovery.
   [junit4]   2> 2054927 INFO  
(coreLoadExecutor-8859-thread-1-processing-n:127.0.0.1:60699_) 
[n:127.0.0.1:60699_ c:collection1   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection collection1
   [junit4]   2> 2054927 INFO  
(coreLoadExecutor-8859-thread-1-processing-n:127.0.0.1:60699_) 
[n:127.0.0.1:60699_ c:collection1   x:collection1] o.a.s.c.SolrCore 
[[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-4-001/cores/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001/shard-4-001/cores/collection1

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

est <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: 
java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 
127.0.0.1:41903/solr within 10000 ms
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A156A4036A7169CC:29029BD9C48D0434]:0)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:182)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:116)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:106)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:226)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.ZkClientClusterStateProvider.connect(ZkClientClusterStateProvider.java:121)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:615)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.initCloud(AbstractFullDistribZkTestBase.java:268)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:330)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:990)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]    > Caused by: java.util.concurrent.TimeoutException: Could not 
connect to ZooKeeper 127.0.0.1:41903/solr within 10000 ms
   [junit4]    >        at 
org.apache.solr.common.cloud.ConnectionManager.waitForConnected(ConnectionManager.java:233)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:174)
   [junit4]    >        ... 40 more
   [junit4]   2> 2230812 INFO  
(SUITE-ShardSplitTest-seed#[A156A4036A7169CC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_A156A4036A7169CC-001
   [junit4]   2> Mar 03, 2017 4:01:32 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{rnd_b=Lucene50(blocksize=128), a_t=BlockTreeOrds(blocksize=128), 
a_i=Lucene50(blocksize=128), id=Lucene50(blocksize=128)}, 
docValues:{rnd_b=DocValuesFormat(name=Direct), 
_version_=DocValuesFormat(name=Lucene54), a_t=DocValuesFormat(name=Lucene54), 
a_i=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Direct)}, 
maxPointsInLeafNode=1905, maxMBSortInHeap=5.378480551695178, 
sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=bg-BG, 
timezone=Asia/Jerusalem
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=194084040,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [TestCoreContainer, 
TestSolrXml, TestFieldSortValues, ClassificationUpdateProcessorTest, 
SolrGangliaReporterTest, CoreAdminCreateDiscoverTest, TestSolrIndexConfig, 
HttpPartitionTest, TestBackupRepositoryFactory, BooleanFieldTest, 
TestSchemaResource, TestEmbeddedSolrServerConstructors, 
ChaosMonkeyNothingIsSafeTest, TestManagedSchemaAPI, HdfsRecoveryZkTest, 
DocValuesNotIndexedTest, TestRequestForwarding, TestConfigSets, SyncSliceTest, 
TestCloudInspectUtil, TestPerFieldSimilarityClassic, ClusterStateUpdateTest, 
TestCSVLoader, OpenExchangeRatesOrgProviderTest, 
TestSolrCloudWithDelegationTokens, TestBinaryResponseWriter, 
StatelessScriptUpdateProcessorFactoryTest, MergeStrategyTest, TestSort, 
TestSortByMinMaxFunction, SoftAutoCommitTest, 
HdfsWriteToMultipleCollectionsTest, TestRebalanceLeaders, 
TestDeleteCollectionOnDownNodes, TimeZoneUtilsTest, 
TestCloudPseudoReturnFields, SolrCoreMetricManagerTest, TestUninvertingReader, 
TestExceedMaxTermLength, SolrCloudExampleTest, DeleteStatusTest, 
AnalysisErrorHandlingTest, TestStressRecovery, BasicAuthIntegrationTest, 
IndexSchemaRuntimeFieldTest, MetricsHandlerTest, TestLocalFSCloudBackupRestore, 
SOLR749Test, BaseCdcrDistributedZkTest, TestSolrConfigHandlerCloud, 
TestClusterProperties, BasicDistributedZkTest, EnumFieldTest, 
SimpleCollectionCreateDeleteTest, HardAutoCommitTest, PeerSyncTest, 
TestDocumentBuilder, TestStressUserVersions, SampleTest, ZkControllerTest, 
TestClusterStateMutator, DocValuesTest, HdfsChaosMonkeySafeLeaderTest, 
TestBlobHandler, TestTolerantSearch, BufferStoreTest, TestMacroExpander, 
SolrCoreTest, OutputWriterTest, TestFieldCacheSortRandom, TestRTimerTree, 
TestConfigSetsAPIZkFailure, TestChildDocTransformer, TestRawTransformer, 
TestSchemalessBufferedUpdates, TestBulkSchemaConcurrent, TestPhraseSuggestions, 
ConjunctionSolrSpellCheckerTest, TestSolrDeletionPolicy1, RemoteQueryErrorTest, 
DisMaxRequestHandlerTest, TestReloadDeadlock, ZkCLITest, MultiThreadedOCPTest, 
TestImpersonationWithHadoopAuth, InfixSuggestersTest, 
DistributedFacetPivotSmallTest, DistributedFacetPivotLongTailTest, 
TestSimpleQParserPlugin, TestRandomCollapseQParserPlugin, 
OverriddenZkACLAndCredentialsProvidersTest, ActionThrottleTest, 
SystemInfoHandlerTest, DistributedQueryElevationComponentTest, TestLazyCores, 
SuggesterFSTTest, PingRequestHandlerTest, XsltUpdateRequestHandlerTest, 
MigrateRouteKeyTest, FastVectorHighlighterTest, TestGraphTermsQParserPlugin, 
TestRTGBase, TestExportWriter, DistributedMLTComponentTest, 
BigEndianAscendingWordDeserializerTest, CachingDirectoryFactoryTest, 
XmlUpdateRequestHandlerTest, DateRangeFieldTest, TestBM25SimilarityFactory, 
TestGraphMLResponseWriter, DataDrivenBlockJoinTest, 
HdfsTlogReplayBufferedWhileIndexingTest, TestImplicitCoreProperties, 
SuggestComponentContextFilterQueryTest, PrimUtilsTest, HdfsSyncSliceTest, 
TestXmlQParser, RequiredFieldsTest, HdfsNNFailoverTest, 
DocumentAnalysisRequestHandlerTest, TestPostingsSolrHighlighter, 
DirectUpdateHandlerTest, BlockJoinFacetSimpleTest, JSONWriterTest, 
CloudExitableDirectoryReaderTest, TestInPlaceUpdatesDistrib, 
HdfsDirectoryFactoryTest, PluginInfoTest, TestFieldCacheWithThreads, 
TestFunctionQuery, TestStressLucene, SchemaVersionSpecificBehaviorTest, 
DistribDocExpirationUpdateProcessorTest, ConnectionManagerTest, 
TestXIncludeConfig, PKIAuthenticationIntegrationTest, TestFieldTypeResource, 
CursorPagingTest, JavabinLoaderTest, VMParamsZkACLAndCredentialsProvidersTest, 
TestBinaryField, TestDFISimilarityFactory, TestPKIAuthenticationPlugin, 
SolrTestCaseJ4Test, TestCustomStream, DirectSolrSpellCheckerTest, 
OverseerStatusTest, PolyFieldTest, TestSubQueryTransformerCrossCore, 
TestElisionMultitermQuery, DeleteReplicaTest, MultiTermTest, 
TestSolrCloudWithSecureImpersonation, LeaderElectionTest, 
UUIDUpdateProcessorFallbackTest, TestScoreJoinQPNoScore, 
CurrencyFieldXmlFileTest, TestReloadAndDeleteDocs, 
DistributedSpellCheckComponentTest, TestReplicationHandler, TestGroupingSearch, 
SolrRequestParserTest, TestSolrJ, HdfsLockFactoryTest, PrimitiveFieldTypeTest, 
TestReqParamsAPI, TestUnifiedSolrHighlighter, TestCollationField, 
BlockJoinFacetDistribTest, TestConfigsApi, OverseerTest, BasicZkTest, 
RecoveryZkTest, TestRandomFaceting, ZkSolrClientTest, TestRandomDVFaceting, 
TestFaceting, TestHashPartitioner, TermVectorComponentDistributedTest, 
TestRealTimeGet, TestStressReorder, TestStressVersions, 
DistributedTermsComponentTest, SimpleFacetsTest, TestSolr4Spatial, 
StatsComponentTest, SpellCheckComponentTest, QueryElevationComponentTest, 
BadIndexSchemaTest, ConvertedLegacyTest, BasicFunctionalityTest, TestBadConfig, 
HighlighterTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, 
SimplePostToolTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, 
TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterTSTTest, 
SpatialFilterTest, SuggesterWFSTTest, WordBreakSolrSpellCheckerTest, 
TestPseudoReturnFields, TestUpdate, TestWordDelimiterFilterFactory, 
QueryEqualityTest, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, 
TestRemoteStreaming, SolrInfoMBeanTest, TestSurroundQueryParser, 
StandardRequestHandlerTest, TestQueryTypes, TestOmitPositions, 
RequestHandlersTest, TestValueSourceCache, TestIndexingPerformance, 
TestSolrQueryParser, RegexBoostProcessorTest, TestJmxIntegration, 
UpdateRequestProcessorFactoryTest, QueryParsingTest, 
UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, 
TestPHPSerializedResponseWriter, TestComponentsName, HighlighterConfigTest, 
UpdateParamsTest, AlternateDirectoryTest, TestQuerySenderNoQuery, 
ResponseLogComponentTest, BadComponentTest, TestSolrDeletionPolicy2, 
TestSearchPerf, NumericFieldsTest, TestFuzzyAnalyzedSuggestions, 
ExternalFileFieldSortTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, 
SpellPossibilityIteratorTest, TestCharFilters, TestCodecSupport, 
SynonymTokenizerTest, EchoParamsTest, TestSweetSpotSimilarityFactory, 
TestPerFieldSimilarity, TestIBSimilarityFactory, TestJmxMonitoredMap, 
TestFastOutputStream, ScriptEngineTest, URLClassifyProcessorTest, 
TestFastLRUCache, PreAnalyzedFieldTest, TestSystemIdResolver, DateFieldTest, 
SpellingQueryConverterTest, DOMUtilTest, ClusterStateTest, TestLRUCache, 
ZkNodePropsTest, SliceStateTest, UUIDFieldTest, FileUtilsTest, 
TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestPathTrie, 
TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, AssignTest, 
AsyncCallRequestStatusResponseTest, CdcrReplicationDistributedZkTest, 
CdcrRequestHandlerTest, CleanupOldIndexTest, CollectionReloadTest, 
CollectionStateFormat2Test, CollectionsAPIAsyncDistributedZkTest, 
ConcurrentDeleteAndCreateCollectionTest, ConfigSetsAPITest, 
CreateCollectionCleanupTest, CustomCollectionTest, ForceLeaderTest, 
OverseerTaskQueueTest, SSLMigrationTest, SaslZkACLProviderTest, ShardSplitTest]
   [junit4] Completed [521/697 (1!)] on J0 in 185.36s, 5 tests, 1 error <<< 
FAILURES!

[...truncated 63239 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to