Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/979/

2 tests failed.
FAILED:  
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy

Error Message:
Could not find collection : c1

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
        at 
__randomizedtesting.SeedInfo.seed([1CB318A5E535F4BA:77FCB8D89C3A2980]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:135)
        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:46)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)


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

Error Message:
You must call setShardName() on this request

Stack Trace:
java.lang.IllegalArgumentException: You must call setShardName() on this request
        at 
__randomizedtesting.SeedInfo.seed([1CB318A5E535F4BA:94E7277F4BC99942]:0)
        at 
org.apache.solr.client.solrj.request.CollectionAdminRequest$AsyncShardSpecificAdminRequest.getParams(CollectionAdminRequest.java:222)
        at 
org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard.getParams(CollectionAdminRequest.java:686)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:802)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:166)
        at 
org.apache.solr.cloud.CollectionsAPISolrJTest.testSplitShard(CollectionsAPISolrJTest.java:218)
        at 
org.apache.solr.cloud.CollectionsAPISolrJTest.test(CollectionsAPISolrJTest.java:62)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:497)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10690 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.overseer.ZkStateReaderTest_1CB318A5E535F4BA-001/init-core-data-001
   [junit4]   2> 251502 INFO  
(SUITE-ZkStateReaderTest-seed#[1CB318A5E535F4BA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
   [junit4]   2> 251504 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 251505 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 251505 INFO  (Thread-513) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 251505 INFO  (Thread-513) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 251605 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer start zk server on port:47455
   [junit4]   2> 251605 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 251606 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 251612 INFO  (zkCallback-162-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@53773b9f 
name:ZooKeeperConnection Watcher:127.0.0.1:47455 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 251612 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 251613 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 251614 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 251615 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 251616 INFO  (zkCallback-163-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6aa6773a 
name:ZooKeeperConnection Watcher:127.0.0.1:47455 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 251616 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 251616 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 251616 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 251620 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 251621 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 251629 INFO  (zkCallback-164-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@228c0197 
name:ZooKeeperConnection Watcher:127.0.0.1:47455/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 251629 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 251629 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 251634 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 251635 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 251636 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 251638 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 251639 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 251643 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 251645 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 251647 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 251648 INFO  (zkCallback-164-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 251650 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 251651 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 251651 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 251651 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Removing watch for uninteresting collection [c1]
   [junit4]   2> 251653 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47455 47455
   [junit4]   2> 252022 INFO  (Thread-513) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:47455 47455
   [junit4]   2> 252023 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 252025 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 252026 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 252026 INFO  (Thread-514) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 252026 INFO  (Thread-514) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 252126 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer start zk server on port:54070
   [junit4]   2> 252126 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252127 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252132 INFO  (zkCallback-165-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1af0def5 
name:ZooKeeperConnection Watcher:127.0.0.1:54070 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 252133 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252133 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252135 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252135 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252143 INFO  (zkCallback-166-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@59120355 
name:ZooKeeperConnection Watcher:127.0.0.1:54070 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 252143 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252143 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252144 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 252148 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252149 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252151 INFO  (zkCallback-167-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@598255f9 
name:ZooKeeperConnection Watcher:127.0.0.1:54070/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 252151 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252151 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252151 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 252153 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 252153 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 252155 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 252155 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 252156 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 252157 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 252158 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 252161 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 252164 INFO  (zkCallback-167-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 252164 WARN  (zkCallback-167-thread-1) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /collections]
   [junit4]   2> 252168 INFO  (zkCallback-167-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [0])
   [junit4]   2> 252168 WARN  (zkCallback-167-thread-2) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /clusterstate.json]
   [junit4]   2> 252173 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54070 54070
   [junit4]   2> 252315 INFO  (Thread-514) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:54070 54070
   [junit4]   2> 252319 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest 
-Dtests.method=testStateFormatUpdateWithExplicitRefreshLazy 
-Dtests.seed=1CB318A5E535F4BA -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=ar-QA -Dtests.timezone=Europe/Kiev -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.30s J1 | 
ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not 
find collection : c1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([1CB318A5E535F4BA:77FCB8D89C3A2980]:0)
   [junit4]    >        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
   [junit4]    >        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:135)
   [junit4]    >        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:46)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 252326 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 252327 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 252327 INFO  (Thread-515) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 252327 INFO  (Thread-515) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 252427 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer start zk server on port:39001
   [junit4]   2> 252427 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252441 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252452 INFO  (zkCallback-168-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@2ba0a2ec 
name:ZooKeeperConnection Watcher:127.0.0.1:39001 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 252452 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252453 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252455 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252468 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252504 INFO  (zkCallback-169-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@2e88f674 
name:ZooKeeperConnection Watcher:127.0.0.1:39001 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 252504 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252505 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252505 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 252508 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252536 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252548 INFO  (zkCallback-170-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4a97f1e5 
name:ZooKeeperConnection Watcher:127.0.0.1:39001/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 252548 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252549 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252549 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 252551 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 252552 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 252557 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 252558 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 252559 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 252560 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 252560 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 252561 INFO  (zkCallback-170-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 252562 INFO  (zkCallback-170-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [0])
   [junit4]   2> 252563 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 252564 INFO  (zkCallback-170-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [0])
   [junit4]   2> 252573 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39001 39001
   [junit4]   2> 252748 INFO  (Thread-515) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:39001 39001
   [junit4]   2> 252749 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 252751 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 252752 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 252756 INFO  (Thread-516) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 252756 INFO  (Thread-516) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 252856 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer start zk server on port:34955
   [junit4]   2> 252856 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252862 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252876 INFO  (zkCallback-171-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@e0b0b63 name:ZooKeeperConnection 
Watcher:127.0.0.1:34955 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 252876 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252877 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252878 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252879 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252889 INFO  (zkCallback-172-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@2c77c45f 
name:ZooKeeperConnection Watcher:127.0.0.1:34955 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 252889 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252889 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252889 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 252892 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 252894 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 252904 INFO  (zkCallback-173-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1ec04ee7 
name:ZooKeeperConnection Watcher:127.0.0.1:34955/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 252904 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 252904 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 252905 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 252906 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 252907 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 252908 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 252910 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 252910 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 252912 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 252912 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 252913 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 252913 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 252913 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 252917 INFO  (zkCallback-173-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 252917 INFO  (zkCallback-173-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [0])
   [junit4]   2> 252919 INFO  (zkCallback-173-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 252967 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 252968 INFO  (zkCallback-173-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [0])
   [junit4]   2> 252969 INFO  (zkCallback-173-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 253021 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34955 34955
   [junit4]   2> 253124 INFO  (Thread-516) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:34955 34955
   [junit4]   2> 253125 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> 253127 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 253128 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 253128 INFO  (Thread-517) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 253128 INFO  (Thread-517) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 253228 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer start zk server on port:52281
   [junit4]   2> 253229 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 253236 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 253254 INFO  (zkCallback-174-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@3241d40f 
name:ZooKeeperConnection Watcher:127.0.0.1:52281 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 253254 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 253254 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 253259 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 253272 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 253290 INFO  (zkCallback-175-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@41310f7b 
name:ZooKeeperConnection Watcher:127.0.0.1:52281 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 253290 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 253290 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 253290 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 253295 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 253301 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 253303 INFO  (zkCallback-176-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@17c860f4 
name:ZooKeeperConnection Watcher:127.0.0.1:52281/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 253303 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 253304 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 253309 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 253313 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 253314 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 253315 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 253316 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 253317 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 253318 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 253319 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 253320 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 253321 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 253322 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 253325 INFO  (zkCallback-176-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 253329 INFO  (zkCallback-176-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [0])
   [junit4]   2> 253329 INFO  (zkCallback-176-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 253330 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 253332 INFO  (zkCallback-176-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [0])
   [junit4]   2> 253333 INFO  (zkCallback-176-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 253337 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52281 52281
   [junit4]   2> 253585 INFO  (Thread-517) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:52281 52281
   [junit4]   2> 253586 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[1CB318A5E535F4BA])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 254253 INFO  
(SUITE-ZkStateReaderTest-seed#[1CB318A5E535F4BA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.overseer.ZkStateReaderTest_1CB318A5E535F4BA-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, 
docValues:{}, maxPointsInLeafNode=1293, maxMBSortInHeap=5.058940137964989, 
sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=ar-QA, 
timezone=Europe/Kiev
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_66 (64-bit)/cpus=4,threads=1,free=334261504,total=426770432
   [junit4]   2> NOTE: All tests run in this JVM: 
[DistributedFacetPivotLongTailTest, TriLevelCompositeIdRoutingTest, 
TestCloudPivotFacet, HdfsLockFactoryTest, TestXmlQParser, 
TestBM25SimilarityFactory, CollectionsAPIAsyncDistributedZkTest, 
DistanceUnitsTest, DistribDocExpirationUpdateProcessorTest, 
SchemaVersionSpecificBehaviorTest, BasicFunctionalityTest, 
TestSurroundQueryParser, SimpleCollectionCreateDeleteTest, 
TestQuerySenderListener, TestCodecSupport, TestExceedMaxTermLength, 
SolrInfoMBeanTest, ZkStateReaderTest]
   [junit4] Completed [58/578 (1!)] on J1 in 2.81s, 5 tests, 1 error <<< 
FAILURES!

[...truncated 1172 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPISolrJTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/init-core-data-001
   [junit4]   2> 1306416 INFO  
(SUITE-CollectionsAPISolrJTest-seed#[1CB318A5E535F4BA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
   [junit4]   2> 1306416 INFO  
(SUITE-CollectionsAPISolrJTest-seed#[1CB318A5E535F4BA]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1306418 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1306419 INFO  (Thread-3960) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1306419 INFO  (Thread-3960) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1306518 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:35523
   [junit4]   2> 1306519 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1306519 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1306522 INFO  (zkCallback-1533-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@70809cbd 
name:ZooKeeperConnection Watcher:127.0.0.1:35523 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1306522 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1306522 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1306522 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1306525 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1306525 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1306526 INFO  (zkCallback-1534-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6783f1e2 
name:ZooKeeperConnection Watcher:127.0.0.1:35523/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1306527 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1306527 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1306527 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1306528 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1306530 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1306531 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1306532 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 1306533 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1306535 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 1306535 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1306537 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1306537 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1306538 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 1306539 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1306540 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 1306540 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1306542 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 1306542 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1306543 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1306543 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1306545 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1306545 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1306546 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1306546 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1306548 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1306548 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1306549 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 1306550 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1306552 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1306552 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1306554 INFO  (zkCallback-1535-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@335edf19 
name:ZooKeeperConnection Watcher:127.0.0.1:35523/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1306555 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1306555 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1306652 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1
   [junit4]   2> 1306658 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.e.j.s.Server jetty-9.3.6.v20151106
   [junit4]   2> 1306660 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2380e6b5{/,null,AVAILABLE}
   [junit4]   2> 1306660 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.e.j.u.s.SslContextFactory x509=X509@20ed9970(solrtest,h=[],w=[]) for 
SslContextFactory@543e8c0b(file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/server/etc/test/solrtest.keystore,file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/server/etc/test/solrtest.keystore)
   [junit4]   2> 1306663 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.e.j.s.ServerConnector Started ServerConnector@435c64f{SSL,[ssl, 
http/1.1]}{127.0.0.1:36853}
   [junit4]   2> 1306664 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.e.j.s.Server Started @1309928ms
   [junit4]   2> 1306664 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/tempDir-001/control/data,
 hostContext=/, hostPort=36853, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores}
   [junit4]   2> 1306664 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 1306665 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001'
   [junit4]   2> 1306665 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1306665 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1306665 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1306666 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1306667 INFO  (zkCallback-1536-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7d28fba7 
name:ZooKeeperConnection Watcher:127.0.0.1:35523/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1306667 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1306667 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1306668 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1306668 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/solr.xml
   [junit4]   2> 1306673 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores
   [junit4]   2> 1306673 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1295239458
   [junit4]   2> 1306673 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001]
   [junit4]   2> 1306673 WARN  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/lib
   [junit4]   2> 1306674 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 1306676 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1306677 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1306677 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1306677 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35523/solr
   [junit4]   2> 1306677 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1306677 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1306678 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1306685 INFO  (zkCallback-1539-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@64803c49 
name:ZooKeeperConnection Watcher:127.0.0.1:35523 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1306685 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1306686 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1306688 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1306689 INFO  
(zkCallback-1540-thread-1-processing-n:127.0.0.1:36853_) [n:127.0.0.1:36853_    
] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5f8bfda0 
name:ZooKeeperConnection Watcher:127.0.0.1:35523/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1306689 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1306690 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1306693 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1306699 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1306701 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1306704 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1306706 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1306707 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1306708 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1306709 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1306710 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1306711 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1306712 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1306714 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1306715 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1306715 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.LeaderElector Joined leadership election with path: 
/overseer_elect/election/95529180887580677-127.0.0.1:36853_-n_0000000000
   [junit4]   2> 1306715 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.OverseerElectionContext I am going to be the leader 
127.0.0.1:36853_
   [junit4]   2> 1306715 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1306717 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.Overseer Overseer 
(id=95529180887580677-127.0.0.1:36853_-n_0000000000) starting
   [junit4]   2> 1306719 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1306723 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=30000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1306724 INFO  
(OverseerStateUpdate-95529180887580677-127.0.0.1:36853_-n_0000000000) 
[n:127.0.0.1:36853_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1306724 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36853_
   [junit4]   2> 1306724 INFO  
(OverseerCollectionConfigSetProcessor-95529180887580677-127.0.0.1:36853_-n_0000000000)
 [n:127.0.0.1:36853_    ] o.a.s.c.OverseerTaskProcessor Process current queue 
of overseer operations
   [junit4]   2> 1306726 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:36853_
   [junit4]   2> 1306727 INFO  
(OverseerStateUpdate-95529180887580677-127.0.0.1:36853_-n_0000000000) 
[n:127.0.0.1:36853_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 1306728 INFO  
(zkCallback-1540-thread-1-processing-n:127.0.0.1:36853_) [n:127.0.0.1:36853_    
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live 
nodes size: [0])
   [junit4]   2> 1306728 INFO  
(zkCallback-1540-thread-1-processing-n:127.0.0.1:36853_) [n:127.0.0.1:36853_    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1306739 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 1306739 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1306740 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores
   [junit4]   2> 1306741 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, collection=control_collection, 
coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 1306741 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1
   [junit4]   2> 1306741 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1306743 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1
   [junit4]   2> 1306743 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [n:127.0.0.1:36853_ 
   ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1306743 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 1306743 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 1306745 INFO  
(OverseerStateUpdate-95529180887580677-127.0.0.1:36853_-n_0000000000) 
[n:127.0.0.1:36853_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:36853";,
   [junit4]   2>   "node_name":"127.0.0.1:36853_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1306745 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 1306746 INFO  
(OverseerStateUpdate-95529180887580677-127.0.0.1:36853_-n_0000000000) 
[n:127.0.0.1:36853_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:36853";,
   [junit4]   2>   "node_name":"127.0.0.1:36853_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1306746 INFO  
(OverseerStateUpdate-95529180887580677-127.0.0.1:36853_-n_0000000000) 
[n:127.0.0.1:36853_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
control_collection
   [junit4]   2> 1306746 INFO  
(OverseerStateUpdate-95529180887580677-127.0.0.1:36853_-n_0000000000) 
[n:127.0.0.1:36853_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 1306748 INFO  
(zkCallback-1540-thread-1-processing-n:127.0.0.1:36853_) [n:127.0.0.1:36853_    
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred 
- updating... (live nodes size: [1])
   [junit4]   2> 1307746 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1307746 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:control_collection
   [junit4]   2> 1307746 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 1307746 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config from: 
[/collections/control_collection]
   [junit4]   2> 1307747 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=[/collections/control_collection] 
[configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1307747 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1'
   [junit4]   2> 1307747 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1307747 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1307748 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 1307753 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 1307756 WARN  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
instead.
   [junit4]   2> 1307757 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1307766 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1307772 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 1307865 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1307871 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
default search field in schema is text
   [junit4]   2> 1307872 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 1307880 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1307882 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1307884 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1307885 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 1307886 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/configs/conf1', 
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1
   [junit4]   2> 1307886 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
control_collection
   [junit4]   2> 1307886 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1307887 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1/data/]
   [junit4]   2> 1307887 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@22ed598d
   [junit4]   2> 1307887 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1/data
   [junit4]   2> 1307887 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1307887 WARN  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1307888 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1/data/index
   [junit4]   2> 1307888 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1307888 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@99eb06 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2326240d),segFN=segments_1,generation=1}
   [junit4]   2> 1307888 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1307889 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36853_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
Looking for old index directories to cleanup for core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1/data/
   [junit4]   2> 1307889 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36853_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001/control-001/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 1307892 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 1307892 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 1307892 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1307892 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 1307892 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1307892 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-explicit"
   [junit4]   2> 1307892 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 1307892 INFO  
(coreLoadExecutor-4627-thread-1-processing-n:127.0.0.1:36853_) 
[n:127.0.0.1:36853_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorCh

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

[junit4]   2>   at 
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:173)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:138)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:59)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:351)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$3(SolrZkClient.java:266)
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$6(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1354392 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.e.j.s.ServerConnector Stopped ServerConnector@53304e2e{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1354392 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6383eed7{/,null,UNAVAILABLE}
   [junit4]   2> 1354394 INFO  
(TEST-CollectionsAPISolrJTest.test-seed#[1CB318A5E535F4BA]) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35523 35523
   [junit4]   2> 1354515 INFO  (Thread-3960) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:35523 35523
   [junit4]   2> 1354516 WARN  (Thread-3960) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/aliases.json
   [junit4]   2>        5       /solr/security.json
   [junit4]   2>        5       /solr/configs/conf1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/clusterstate.json
   [junit4]   2>        3       /solr/collections/solrj_implicit/state.json
   [junit4]   2>        2       
/solr/collections/solrj_test_splitshard/state.json
   [junit4]   2>        2       
/solr/collections/solrj_newstateformat/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/live_nodes
   [junit4]   2>        6       /solr/collections
   [junit4]   2>        4       /solr/overseer/collection-queue-work
   [junit4]   2>        3       /solr/overseer/queue
   [junit4]   2>        2       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=CollectionsAPISolrJTest -Dtests.method=test 
-Dtests.seed=1CB318A5E535F4BA -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=et-EE -Dtests.timezone=Etc/UTC -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   48.1s J1 | CollectionsAPISolrJTest.test <<<
   [junit4]    > Throwable #1: java.lang.IllegalArgumentException: You must 
call setShardName() on this request
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([1CB318A5E535F4BA:94E7277F4BC99942]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.request.CollectionAdminRequest$AsyncShardSpecificAdminRequest.getParams(CollectionAdminRequest.java:222)
   [junit4]    >        at 
org.apache.solr.client.solrj.request.CollectionAdminRequest$SplitShard.getParams(CollectionAdminRequest.java:686)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:802)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:166)
   [junit4]    >        at 
org.apache.solr.cloud.CollectionsAPISolrJTest.testSplitShard(CollectionsAPISolrJTest.java:218)
   [junit4]    >        at 
org.apache.solr.cloud.CollectionsAPISolrJTest.test(CollectionsAPISolrJTest.java:62)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:996)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1354519 INFO  
(SUITE-CollectionsAPISolrJTest-seed#[1CB318A5E535F4BA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPISolrJTest_1CB318A5E535F4BA-001
   [junit4]   2> Mar 11, 2016 1:27:48 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: 
codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
 chunkSize=2, maxDocsPerChunk=192, blockSize=208), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, 
chunkSize=2, blockSize=208)), sim=RandomSimilarity(queryNorm=true,coord=no): 
{}, locale=et-EE, timezone=Etc/UTC
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_66 (64-bit)/cpus=4,threads=1,free=297931448,total=510132224
   [junit4]   2> NOTE: All tests run in this JVM: 
[DistributedFacetPivotLongTailTest, TriLevelCompositeIdRoutingTest, 
TestCloudPivotFacet, HdfsLockFactoryTest, TestXmlQParser, 
TestBM25SimilarityFactory, CollectionsAPIAsyncDistributedZkTest, 
DistanceUnitsTest, DistribDocExpirationUpdateProcessorTest, 
SchemaVersionSpecificBehaviorTest, BasicFunctionalityTest, 
TestSurroundQueryParser, SimpleCollectionCreateDeleteTest, 
TestQuerySenderListener, TestCodecSupport, TestExceedMaxTermLength, 
SolrInfoMBeanTest, ZkStateReaderTest, DOMUtilTest, 
TestRandomCollapseQParserPlugin, TestSQLHandler, TestCustomDocTransformer, 
TestSolrConfigHandler, SpellCheckComponentTest, TestManagedResourceStorage, 
ShardRoutingCustomTest, CollectionReloadTest, MigrateRouteKeyTest, 
TestDocumentBuilder, SolrIndexSplitterTest, OverseerStatusTest, 
SpatialFilterTest, CdcrReplicationHandlerTest, FullHLLTest, TestStressVersions, 
QueryResultKeyTest, TestSolrQueryParserResource, TestCoreContainer, 
AliasIntegrationTest, SpellPossibilityIteratorTest, 
TestMiniSolrCloudClusterBase, RuleEngineTest, ShardRoutingTest, 
DistributedDebugComponentTest, DateRangeFieldTest, TestHashPartitioner, 
TestAuthenticationFramework, PreAnalyzedUpdateProcessorTest, JavabinLoaderTest, 
DistributedIntervalFacetingTest, TestSimpleTrackingShardHandler, 
TestFieldSortValues, SparseHLLTest, RemoteQueryErrorTest, 
TestDynamicFieldCollectionResource, TestClassicSimilarityFactory, 
TestFreeTextSuggestions, CoreAdminCreateDiscoverTest, MoreLikeThisHandlerTest, 
DocumentBuilderTest, TestNRTOpen, BlockJoinFacetDistribTest, TestLRUStatsCache, 
TestSolrDeletionPolicy2, TestIntervalFaceting, 
TestSha256AuthenticationProvider, SortSpecParsingTest, TestSolrCLIRunExample, 
TestAnalyzeInfixSuggestions, TermVectorComponentTest, TestFastLRUCache, 
AddSchemaFieldsUpdateProcessorFactoryTest, TestSimpleQParserPlugin, 
CdcrUpdateLogTest, TestSizeLimitedDistributedMap, ZkControllerTest, 
FastVectorHighlighterTest, HdfsThreadLeakTest, TestAuthorizationFramework, 
DistanceFunctionTest, CollectionsAPIDistributedZkTest, TestFiltering, 
HdfsRecoveryZkTest, DistributedSuggestComponentTest, 
TestLMJelinekMercerSimilarityFactory, TimeZoneUtilsTest, SortByFunctionTest, 
TestWordDelimiterFilterFactory, DistributedTermsComponentTest, 
TestRequestForwarding, TestFaceting, DistributedSpellCheckComponentTest, 
TestStressReorder, SimpleFacetsTest, SolrCmdDistributorTest, PeerSyncTest, 
TestBadConfig, TestIndexSearcher, ShowFileRequestHandlerTest, 
CurrencyFieldOpenExchangeTest, SimplePostToolTest, 
SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SuggesterFSTTest, 
TestFoldingMultitermQuery, DocValuesTest, TestTrie, SuggesterWFSTTest, 
SolrCoreCheckLockOnStartupTest, TestUpdate, 
StatelessScriptUpdateProcessorFactoryTest, TestQueryUtils, TestWriterPerf, 
PrimitiveFieldTypeTest, TermsComponentTest, RequiredFieldsTest, 
TestSolrQueryParser, RegexBoostProcessorTest, IndexSchemaTest, 
TestCollationField, ReturnFieldsTest, JSONWriterTest, MBeansHandlerTest, 
UniqFieldsUpdateProcessorFactoryTest, TestPartialUpdateDeduplication, 
PingRequestHandlerTest, TestBinaryResponseWriter, TestLFUCache, 
CdcrVersionReplicationTest, CollectionsAPISolrJTest]
   [junit4] Completed [423/578 (2!)] on J1 in 48.62s, 1 test, 1 error <<< 
FAILURES!

[...truncated 499 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:740: The 
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:684: The 
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:59: The 
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/common-build.xml:524:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1457:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1014:
 There were test failures: 578 suites (10 ignored), 2307 tests, 2 errors, 86 
ignored (70 assumptions) [seed: 1CB318A5E535F4BA]

Total time: 59 minutes 5 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


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

Reply via email to