Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/768/

4 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest

Error Message:
ERROR: SolrIndexSearcher opens=31 closes=30

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=31 closes=30
        at __randomizedtesting.SeedInfo.seed([EF6380E83FB00BFF]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:467)
        at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:233)
        at sun.reflect.GeneratedMethodAccessor70.invoke(Unknown Source)
        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:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
        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:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        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:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest

Error Message:
2 threads leaked from SUITE scope at 
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest:     1) Thread[id=24187, 
name=qtp703560321-24187, state=WAITING, 
group=TGRP-ChaosMonkeyNothingIsSafeTest]         at 
java.lang.Object.wait(Native Method)         at 
java.lang.Object.wait(Object.java:502)         at 
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:404)
         at 
org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
         at 
org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:231)
         at 
org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)    
     at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:781)
         at 
org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1655)
         at 
org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
         at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
         at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:151)
         at org.apache.solr.core.SolrCore.execute(SolrCore.java:2079)         
at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:667)         
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460)         at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:210)
         at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:179)
         at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
         at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:106)
         at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
         at 
org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)    
     at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)     
    at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
         at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)      
   at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
         at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
         at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)       
  at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
         at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
         at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)   
      at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)  
       at org.eclipse.jetty.server.Server.handle(Server.java:499)         at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)         at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)     
    at 
org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)      
   at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) 
        at java.lang.Thread.run(Thread.java:745)    2) Thread[id=24209, 
name=searcherExecutor-7090-thread-1, state=WAITING, 
group=TGRP-ChaosMonkeyNothingIsSafeTest]         at sun.misc.Unsafe.park(Native 
Method)         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
         at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)     
    at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)   
      at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) 
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
        at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE 
scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest: 
   1) Thread[id=24187, name=qtp703560321-24187, state=WAITING, 
group=TGRP-ChaosMonkeyNothingIsSafeTest]
        at java.lang.Object.wait(Native Method)
        at java.lang.Object.wait(Object.java:502)
        at 
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient.blockUntilFinished(ConcurrentUpdateSolrClient.java:404)
        at 
org.apache.solr.update.StreamingSolrClients.blockUntilFinished(StreamingSolrClients.java:103)
        at 
org.apache.solr.update.SolrCmdDistributor.blockAndDoRetries(SolrCmdDistributor.java:231)
        at 
org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:89)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:781)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1655)
        at 
org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:183)
        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:151)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2079)
        at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:667)
        at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460)
        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:210)
        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:179)
        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:106)
        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
        at 
org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
        at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)
        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
        at org.eclipse.jetty.server.Server.handle(Server.java:499)
        at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
        at 
org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=24209, name=searcherExecutor-7090-thread-1, state=WAITING, 
group=TGRP-ChaosMonkeyNothingIsSafeTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
        at 
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
        at __randomizedtesting.SeedInfo.seed([EF6380E83FB00BFF]:0)


REGRESSION:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=6229, name=collection3, 
state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=6229, name=collection3, state=RUNNABLE, 
group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:59445/vgva: collection already exists: 
awholynewstresscollection_collection3_6
        at __randomizedtesting.SeedInfo.seed([EF6380E83FB00BFF]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888)


FAILED:  org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=77534, name=collection1, 
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=77534, name=collection1, state=RUNNABLE, 
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:53474: Could not find collection : 
awholynewstresscollection_collection1_0
        at __randomizedtesting.SeedInfo.seed([EF6380E83FB00BFF]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895)




Build Log:
[...truncated 9985 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/init-core-data-001
   [junit4]   2> 780294 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF6380E83FB00BFF]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 780295 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF6380E83FB00BFF]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /vgva/
   [junit4]   2> 780301 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 780302 INFO  (Thread-3676) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 780302 INFO  (Thread-3676) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 780402 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:39273
   [junit4]   2> 780402 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 780417 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 780445 INFO  (zkCallback-428-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@308f028c 
name:ZooKeeperConnection Watcher:127.0.0.1:39273 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 780445 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 780446 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 780446 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 780456 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 780462 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 780504 INFO  (zkCallback-429-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@130e963e 
name:ZooKeeperConnection Watcher:127.0.0.1:39273/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 780504 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 780505 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 780505 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 780508 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 780514 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 780516 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 780518 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 780518 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 780520 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 780521 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 780522 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 780523 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 780524 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 780525 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 780526 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 780526 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 780528 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 780528 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 780529 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 780530 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 780531 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 780531 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 780533 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 780533 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 780534 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 780535 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 780536 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 780536 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 781107 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1
   [junit4]   2> 781128 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 781146 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@48fd1a20{/vgva,null,AVAILABLE}
   [junit4]   2> 781148 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@3faadf0a{HTTP/1.1}{127.0.0.1:58454}
   [junit4]   2> 781148 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.e.j.s.Server Started @786511ms
   [junit4]   2> 781148 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/tempDir-001/control/data,
 hostContext=/vgva, hostPort=58454, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores}
   [junit4]   2> 781149 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 781149 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/'
   [junit4]   2> 781198 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 781207 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 781218 INFO  (zkCallback-430-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@518fd33d 
name:ZooKeeperConnection Watcher:127.0.0.1:39273/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 781218 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 781219 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 781222 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 781222 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/solr.xml
   [junit4]   2> 781235 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores
   [junit4]   2> 781235 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1006353199
   [junit4]   2> 781235 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/]
   [junit4]   2> 781236 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/lib
   [junit4]   2> 781236 WARN  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/lib).
   [junit4]   2> 781256 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
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> 781257 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 781257 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 781258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 781258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39273/solr
   [junit4]   2> 781258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 781258 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 781263 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 781286 INFO  (zkCallback-432-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@218b2e69 
name:ZooKeeperConnection Watcher:127.0.0.1:39273 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 781289 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 781289 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 781298 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 781306 INFO  
(zkCallback-433-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@61103bf0 
name:ZooKeeperConnection Watcher:127.0.0.1:39273/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 781306 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 781308 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 781310 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 781313 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 781314 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 781316 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 781318 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 781319 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 781320 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 781321 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 781322 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:58454_vgva
   [junit4]   2> 781322 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:58454_vgva
   [junit4]   2> 781324 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 781325 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 781326 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 781327 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.LeaderElector Joined leadership election 
with path: 
/overseer_elect/election/94367212718325764-127.0.0.1:58454_vgva-n_0000000000
   [junit4]   2> 781327 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:58454_vgva
   [junit4]   2> 781327 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/leader
   [junit4]   2> 781328 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.Overseer Overseer 
(id=94367212718325764-127.0.0.1:58454_vgva-n_0000000000) starting
   [junit4]   2> 781331 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/queue-work
   [junit4]   2> 781342 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=10000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 781343 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 781344 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.ZkStateReader Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 781353 INFO  
(OverseerCollectionProcessor-94367212718325764-127.0.0.1:58454_vgva-n_0000000000)
 [n:127.0.0.1:58454_vgva    ] o.a.s.c.OverseerProcessor Process current queue 
of overseer operations
   [junit4]   2> 781362 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.ZkStateReader Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 781400 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 781400 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.CoreContainer No authentication plugin 
used.
   [junit4]   2> 781401 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.CoresLocator Looking for core definitions 
underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores
   [junit4]   2> 781402 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.SolrCore Created CoreDescriptor: 
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1,
 collection=control_collection, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/,
 coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 781402 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/
   [junit4]   2> 781402 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 781404 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.ZkController publishing state=down
   [junit4]   2> 781405 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.ZkController numShards not found on descriptor - reading it from system 
property
   [junit4]   2> 781406 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.ZkController look for our core node name
   [junit4]   2> 781406 INFO  
(zkCallback-433-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired 
on path /overseer/queue state SyncConnected
   [junit4]   2> 781407 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:58454/vgva";,
   [junit4]   2>   "node_name":"127.0.0.1:58454_vgva",
   [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> 781408 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:58454/vgva";,
   [junit4]   2>   "node_name":"127.0.0.1:58454_vgva",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 781408 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.o.ClusterStateMutator building a new 
cName: control_collection
   [junit4]   2> 781408 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard1
   [junit4]   2> 781409 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 781409 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:58454_vgva    ] o.a.s.s.SolrDispatchFilter 
SolrDispatchFilter.init() done
   [junit4]   2> 781409 INFO  
(zkCallback-433-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] 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> 781409 INFO  
(zkCallback-433-thread-2-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] 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> 782406 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 782406 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 782407 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 782407 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config 
from:/collections/control_collection
   [junit4]   2> 782408 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 
specified config exists in ZooKeeper
   [junit4]   2> 782408 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/'
   [junit4]   2> 782424 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] o.a.s.c.Config 
loaded config solrconfig.xml with version 0 
   [junit4]   2> 782428 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 782432 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 782439 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] o.a.s.c.Config 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 782442 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 782452 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 782566 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 782572 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 782573 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 782590 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 782593 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 782596 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 782597 WARN  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 782597 WARN  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 782597 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 782598 WARN  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 782598 WARN  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 782600 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/configs/conf1', 
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 782600 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection   x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
collection control_collection
   [junit4]   2> 782600 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 782601 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/],
 dataDir=[null]
   [junit4]   2> 782601 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva 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@5cb37d16
   [junit4]   2> 782602 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/data
   [junit4]   2> 782602 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/data/index/
   [junit4]   2> 782603 WARN  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 782603 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/data/index
   [junit4]   2> 782603 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 782604 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3db8b1e6 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77d40f57),segFN=segments_1,generation=1}
   [junit4]   2> 782604 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 782608 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 782608 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 782608 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 782608 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 782608 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 782609 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva 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> 782609 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva 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> 782609 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 782609 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 782611 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 782612 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 782613 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 782614 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 782618 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 782618 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 782619 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
Looking for old index directories to cleanup for core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/data/
   [junit4]   2> 782619 WARN  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.DirectoryFactory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/control-001/cores/collection1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 782623 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 782623 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 782624 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 782624 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 782624 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva 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=32, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 782625 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=RawDirectoryWrapper(RAMDirectory@3db8b1e6 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77d40f57),segFN=segments_1,generation=1}
   [junit4]   2> 782625 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 782625 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3de499c6[collection1] 
main
   [junit4]   2> 782625 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.c.ZkStateReader Load collection config 
from:/collections/control_collection
   [junit4]   2> 782626 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection 
configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 782626 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based 
storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 782627 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO 
with znodeBase: /configs/conf1
   [junit4]   2> 782627 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 782627 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 782627 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode 
/configs/conf1/_rest_managed.json
   [junit4]   2> 782628 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path 
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 782628 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 782628 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 782628 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 782628 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 782629 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 782629 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1509875405932199936
   [junit4]   2> 782631 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with 
highest version 1509875405932199936
   [junit4]   2> 782631 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 782632 INFO  
(searcherExecutor-1569-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
[collection1] Registered new searcher Searcher@3de499c6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 782632 INFO  
(coreLoadExecutor-1568-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 782638 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
Register replica - core:collection1 address:http://127.0.0.1:58454/vgva 
collection:control_collection shard:shard1
   [junit4]   2> 782639 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: 
/collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 782643 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector 
Joined leadership election with path: 
/collections/control_collection/leader_elect/shard1/election/94367212718325764-core_node1-n_0000000000
   [junit4]   2> 782644 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 782647 INFO  
(zkCallback-433-thread-2-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired 
on path /overseer/queue state SyncConnected
   [junit4]   2> 782647 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 782647 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 782647 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:58454/vgva/collection1/
   [junit4]   2> 782647 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 782647 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection"} current state version: 1
   [junit4]   2> 782648 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:58454/vgva/collection1/ has no replicas
   [junit4]   2> 782648 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:58454/vgva/collection1/ shard1
   [junit4]   2> 782648 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 782651 INFO  
(zkCallback-433-thread-2-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired 
on path /overseer/queue state SyncConnected
   [junit4]   2> 782652 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:58454/vgva";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 782754 INFO  
(zkCallback-433-thread-2-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] 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> 782754 INFO  
(zkCallback-433-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] 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> 782803 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
We are http://127.0.0.1:58454/vgva/collection1/ and leader is 
http://127.0.0.1:58454/vgva/collection1/
   [junit4]   2> 782803 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58454/vgva
   [junit4]   2> 782803 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 782804 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
publishing state=active
   [junit4]   2> 782804 INFO  
(coreZkRegister-1562-thread-1-processing-n:127.0.0.1:58454_vgva x:collection1 
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58454_vgva 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 782805 INFO  
(zkCallback-433-thread-2-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired 
on path /overseer/queue state SyncConnected
   [junit4]   2> 782806 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:58454/vgva";,
   [junit4]   2>   "node_name":"127.0.0.1:58454_vgva",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 782807 INFO  
(OverseerStateUpdate-94367212718325764-127.0.0.1:58454_vgva-n_0000000000) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:58454/vgva";,
   [junit4]   2>   "node_name":"127.0.0.1:58454_vgva",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 782910 INFO  
(zkCallback-433-thread-2-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] 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> 782911 INFO  
(zkCallback-433-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] 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> 782911 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 782913 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 782914 INFO  (zkCallback-435-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@66d72dd5 
name:ZooKeeperConnection Watcher:127.0.0.1:39273/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 782914 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 782915 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 782915 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 782917 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 783267 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/cores/collection1
   [junit4]   2> 783268 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001
   [junit4]   2> 783274 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 783332 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3d890d21{/vgva,null,AVAILABLE}
   [junit4]   2> 783333 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@878b518{HTTP/1.1}{127.0.0.1:47653}
   [junit4]   2> 783333 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.e.j.s.Server Started @788696ms
   [junit4]   2> 783333 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/tempDir-001/jetty1,
 solrconfig=solrconfig.xml, hostContext=/vgva, hostPort=47653, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/cores}
   [junit4]   2> 783334 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 783334 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/'
   [junit4]   2> 783351 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 783367 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 783375 INFO  (zkCallback-436-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@24f251ac 
name:ZooKeeperConnection Watcher:127.0.0.1:39273/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 783375 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 783376 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 783377 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 783377 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/solr.xml
   [junit4]   2> 783382 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/cores
   [junit4]   2> 783382 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 28478889
   [junit4]   2> 783383 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/]
   [junit4]   2> 783383 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/lib
   [junit4]   2> 783383 WARN  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/lib).
   [junit4]   2> 783390 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
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> 783392 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 783392 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 783392 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 783392 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39273/solr
   [junit4]   2> 783392 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 783393 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 783395 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 783402 INFO  (zkCallback-438-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@553e717c 
name:ZooKeeperConnection Watcher:127.0.0.1:39273 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 783422 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 783423 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 783442 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 783468 INFO  
(zkCallback-439-thread-1-processing-n:127.0.0.1:47653_vgva) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5c6b11a4 
name:ZooKeeperConnection Watcher:127.0.0.1:39273/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 783470 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 783474 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.c.ZkStateReader Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 784477 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:47653_vgva
   [junit4]   2> 784478 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:47653_vgva
   [junit4]   2> 784479 INFO  (zkCallback-435-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live 
nodes size: 1)
   [junit4]   2> 784479 INFO  
(zkCallback-433-thread-2-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.ZkStateReader A live node change: 
WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has 
occurred - updating... (live nodes size: 1)
   [junit4]   2> 784479 INFO  
(zkCallback-433-thread-1-processing-n:127.0.0.1:58454_vgva) 
[n:127.0.0.1:58454_vgva    ] o.a.s.c.c.ZkStateReader A live node change: 
WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has 
occurred - updating... (live nodes size: 1)
   [junit4]   2> 784481 INFO  
(zkCallback-439-thread-1-processing-n:127.0.0.1:47653_vgva) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.c.ZkStateReader A live node change: 
WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has 
occurred - updating... (live nodes size: 1)
   [junit4]   2> 784482 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 784482 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.LeaderElector Joined leadership election 
with path: 
/overseer_elect/election/94367212718325768-127.0.0.1:47653_vgva-n_0000000001
   [junit4]   2> 784483 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.LeaderElector Watching path 
/overseer_elect/election/94367212718325764-127.0.0.1:58454_vgva-n_0000000000 to 
know if I could be the leader
   [junit4]   2> 784539 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 784540 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.CoreContainer No authentication plugin 
used.
   [junit4]   2> 784540 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.CoresLocator Looking for core definitions 
underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/cores
   [junit4]   2> 784541 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.SolrCore Created CoreDescriptor: 
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, 
loadOnStartup=true, configSetProperties=configsetprops.json, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/cores/collection1,
 collection=collection1, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF6380E83FB00BFF-001/shard-1-001/cores/collection1/,
 coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 784542 INFO  
(TEST-CollectionsAPIDistributedZkTest.test-seed#[EF6380E83FB00BFF]) 
[n:127.0.0.1:47653_vgva    ] o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cl

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

.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699)
   [junit4]   2>        at 
org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197)
   [junit4]   2>        at 
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50)
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method)
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:497)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
   [junit4]   2>        ... 53 more
   [junit4]   2> 6748222 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EF6380E83FB00BFF]-worker) 
[n:127.0.0.1:43635_ c:addReplicaColl s:shard1 r:core_node1 
x:addReplicaColl_shard1_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Aug 18, 2015 6:02:42 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): 
{other_tl1=PostingsFormat(name=Memory doPackFST= true), 
range_facet_l_dv=PostingsFormat(name=LuceneFixedGap), 
_version_=PostingsFormat(name=Memory doPackFST= true), multiDefault=FSTOrd50, 
a_t=PostingsFormat(name=Memory doPackFST= true), 
intDefault=PostingsFormat(name=Memory doPackFST= true), 
a_i1=PostingsFormat(name=Memory doPackFST= true), 
id=PostingsFormat(name=LuceneFixedGap), 
range_facet_i_dv=PostingsFormat(name=Memory doPackFST= true), 
text=PostingsFormat(name=Memory doPackFST= false), 
range_facet_l=PostingsFormat(name=Memory doPackFST= true), 
timestamp=PostingsFormat(name=Memory doPackFST= true)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), 
range_facet_i_dv=DocValuesFormat(name=Lucene50), 
timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=es_CU, 
timezone=Australia/Queensland
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_45 (64-bit)/cpus=4,threads=2,free=81295496,total=528482304
   [junit4]   2> NOTE: All tests run in this JVM: [AnalyticsQueryTest, 
TestConfigSets, CacheHeaderTest, TestCopyFieldCollectionResource, ZkCLITest, 
TestCollapseQParserPlugin, FacetPivotSmallTest, 
TestManagedSynonymFilterFactory, ReplicationFactorTest, 
FieldAnalysisRequestHandlerTest, CopyFieldTest, DistribCursorPagingTest, 
DocValuesMultiTest, TestIntervalFaceting, EchoParamsTest, CustomCollectionTest, 
TestLeaderElectionZkExpiry, OverseerRolesTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, TestRestoreCore, 
CoreAdminHandlerTest, ZkControllerTest, CurrencyFieldOpenExchangeTest, 
ConvertedLegacyTest, HLLUtilTest, JSONWriterTest, TestSolrXml, 
TestSolrDeletionPolicy2, TestMissingGroups, TestLMDirichletSimilarityFactory, 
TestSchemaManager, ResourceLoaderTest, TestHighFrequencyDictionaryFactory, 
LukeRequestHandlerTest, HdfsThreadLeakTest, DistanceFunctionTest, 
TestJsonRequest, RAMDirectoryFactoryTest, TestFieldCollectionResource, 
SystemInfoHandlerTest, HdfsChaosMonkeySafeLeaderTest, 
CollectionsAPIDistributedZkTest, TestCoreDiscovery, TestManagedSchema, 
SpellingQueryConverterTest, HdfsUnloadDistributedZkTest, TestCoreContainer, 
TestReloadAndDeleteDocs, DistributedTermsComponentTest, TestRTimerTree, 
AsyncCallRequestStatusResponseTest, TestDistributedGrouping, SuggesterTSTTest, 
ShowFileRequestHandlerTest, DistributedFacetPivotSmallTest, 
TestHighlightDedupGrouping, UpdateParamsTest, TestSearchPerf, 
ZkStateWriterTest, SpellPossibilityIteratorTest, TestReRankQParserPlugin, 
ReturnFieldsTest, TestCollectionAPI, DistanceUnitsTest, 
TestSuggestSpellingConverter, TestAddFieldRealTimeGet, 
TestManagedSchemaDynamicFieldResource, CdcrRequestHandlerTest, 
TestAtomicUpdateErrorCases, DistributedExpandComponentTest, 
TestLMJelinekMercerSimilarityFactory, MigrateRouteKeyTest, 
RemoteQueryErrorTest, TestSolrDynamicMBean, TestCloudInspectUtil, 
TestWriterPerf, RankQueryTest, TestOmitPositions, JsonLoaderTest, 
UUIDUpdateProcessorFallbackTest, AlternateDirectoryTest, AtomicUpdatesTest, 
ChaosMonkeySafeLeaderTest, AssignTest, 
StatelessScriptUpdateProcessorFactoryTest, SaslZkACLProviderTest, 
TestStressUserVersions, TestLFUCache, TestSimpleQParserPlugin, 
BasicDistributedZkTest, BasicDistributedZk2Test, SyncSliceTest, 
OpenCloseCoreStressTest, ShardRoutingTest, BasicZkTest, ClusterStateUpdateTest, 
TestRandomFaceting, ZkSolrClientTest, ShardRoutingCustomTest, 
TestDistributedSearch, PreAnalyzedFieldTest, DateFieldTest, ClusterStateTest, 
TestLRUCache, TestUtils, TestDocumentBuilder, SliceStateTest, FileUtilsTest, 
DistributedMLTComponentTest, TestRTGBase, DistributedIntervalFacetingTest, 
SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, 
TestDistributedMissingSort, TestSimpleTrackingShardHandler, 
TestJettySolrRunner, ActionThrottleTest, AliasIntegrationTest, 
AsyncMigrateRouteKeyTest, CdcrReplicationHandlerTest, CollectionReloadTest, 
DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, 
DistribDocExpirationUpdateProcessorTest, MultiThreadedOCPTest, 
SharedFSAutoReplicaFailoverUtilsTest, SolrCloudExampleTest, SolrXmlInZkTest, 
TestAuthenticationFramework, TestMiniSolrCloudClusterSSL, 
TestRandomRequestDistribution, TestShortCircuitedRequests, 
TestSolrCloudWithKerberosAlt, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [540/540] on J1 in 1523.43s, 1 test, 1 error <<< FAILURES!

[...truncated 13 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:514:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1429:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:984:
 There were test failures: 540 suites, 2131 tests, 2 suite-level errors, 2 
errors, 26 ignored (14 assumptions)

Total time: 267 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to