lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-6.x - Build # 97 - Still Failing
Date Mon, 20 Jun 2016 18:17:54 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/97/

2 tests failed.
FAILED:  org.apache.solr.update.processor.TestNamedUpdateProcessors.test

Error Message:
Index: 0, Size: 0

Stack Trace:
java.lang.IndexOutOfBoundsException: Index: 0, Size: 0
	at __randomizedtesting.SeedInfo.seed([E7B830774568D30B:6FEC0FADEB94BEF3]:0)
	at java.util.ArrayList.rangeCheck(ArrayList.java:653)
	at java.util.ArrayList.get(ArrayList.java:429)
	at org.apache.solr.update.processor.TestNamedUpdateProcessors.test(TestNamedUpdateProcessors.java:128)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)


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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=4455, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:43886: collection already exists: awholynewstresscollection_collection4_0
	at __randomizedtesting.SeedInfo.seed([E7B830774568D30B]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:403)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:356)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)




Build Log:
[...truncated 11181 lines...]
   [junit4] Suite: org.apache.solr.update.processor.TestNamedUpdateProcessors
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/init-core-data-001
   [junit4]   2> 257272 INFO  (SUITE-TestNamedUpdateProcessors-seed#[E7B830774568D30B]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 257272 INFO  (SUITE-TestNamedUpdateProcessors-seed#[E7B830774568D30B]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 257288 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 257288 INFO  (Thread-10202) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 257288 INFO  (Thread-10202) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 257392 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.ZkTestServer start zk server on port:58662
   [junit4]   2> 257392 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 257396 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 257402 INFO  (zkCallback-174-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@27c9e7f1 name:ZooKeeperConnection Watcher:127.0.0.1:58662 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 257402 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 257402 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 257402 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 257432 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 257454 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 257464 INFO  (zkCallback-175-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23e363a3 name:ZooKeeperConnection Watcher:127.0.0.1:58662/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 257464 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 257464 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 257464 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 257473 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 257474 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 257474 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 257475 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 257475 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 257476 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 257476 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 257477 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 257477 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 257478 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 257478 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 257478 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 257478 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 257479 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 257479 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 257480 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 257480 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 257480 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 257480 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 257481 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 257481 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 257482 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 257482 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 257483 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 257483 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 257551 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1
   [junit4]   2> 257552 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 257555 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@34eff71c{/,null,AVAILABLE}
   [junit4]   2> 257557 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.e.j.s.ServerConnector Started ServerConnector@5e3c3dab{HTTP/1.1,[http/1.1]}{127.0.0.1:60967}
   [junit4]   2> 257557 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.e.j.s.Server Started @261024ms
   [junit4]   2> 257557 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/tempDir-001/control/data, hostContext=/, hostPort=60967, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores}
   [junit4]   2> 257557 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 257557 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001'
   [junit4]   2> 257557 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 257557 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 257557 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 257565 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 257566 INFO  (zkCallback-176-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@688758fb name:ZooKeeperConnection Watcher:127.0.0.1:58662/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 257566 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 257566 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 257567 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 257567 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/solr.xml
   [junit4]   2> 257571 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores
   [junit4]   2> 257571 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.CoreContainer New CoreContainer 965763780
   [junit4]   2> 257571 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001]
   [junit4]   2> 257571 WARN  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/lib
   [junit4]   2> 257571 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] 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> 257572 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 257572 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 257572 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 257573 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58662/solr
   [junit4]   2> 257573 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 257573 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 257592 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 257604 INFO  (zkCallback-179-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@480cb530 name:ZooKeeperConnection Watcher:127.0.0.1:58662 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 257606 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 257606 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 257608 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$18/1514911253@2106bb08
   [junit4]   2> 257628 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 257628 INFO  (zkCallback-180-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68364c40 name:ZooKeeperConnection Watcher:127.0.0.1:58662/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 257628 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 257629 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 257630 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 257632 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 257632 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 257633 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 257634 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 257644 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 257645 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 257645 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 257648 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 257648 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 257649 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 257650 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 257673 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 257674 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 257674 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96104653177028612-127.0.0.1:60967_-n_0000000000
   [junit4]   2> 257674 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60967_
   [junit4]   2> 257674 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 257675 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.Overseer Overseer (id=96104653177028612-127.0.0.1:60967_-n_0000000000) starting
   [junit4]   2> 257675 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 257744 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 257745 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 257757 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 257759 INFO  (OverseerCollectionConfigSetProcessor-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 257761 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60967_
   [junit4]   2> 257772 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60967_
   [junit4]   2> 257773 INFO  (zkCallback-180-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 257773 INFO  (zkCallback-180-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 257797 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 257797 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 257798 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores
   [junit4]   2> 257798 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 257798 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1
   [junit4]   2> 257799 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 257808 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 257808 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 257810 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] 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:60967",
   [junit4]   2>   "node_name":"127.0.0.1:60967_",
   [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> 257810 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] 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:60967",
   [junit4]   2>   "node_name":"127.0.0.1:60967_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 257810 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 257811 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 257812 INFO  (zkCallback-180-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] 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> 257816 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible
   [junit4]   2> 257824 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 257985 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2
   [junit4]   2> 257985 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:60967_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 258000 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 258000 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 258000 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 258001 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 258001 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 258002 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 258002 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1'
   [junit4]   2> 258002 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 258002 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 258003 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 258018 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 258021 WARN  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 258032 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.2.0
   [junit4]   2> 258045 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 258049 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 258134 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 258139 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 258140 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 258154 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 258156 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 258157 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 258157 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 258158 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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-6.x/solr/build/solr-core/test/J2
   [junit4]   2> 258158 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 258158 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 258158 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1/data/]
   [junit4]   2> 258158 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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@7ea635e2
   [junit4]   2> 258159 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1/data
   [junit4]   2> 258159 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1/data/index/
   [junit4]   2> 258159 WARN  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 258159 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1/data/index
   [junit4]   2> 258159 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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=46, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.6826171875, noCFSRatio=0.0]
   [junit4]   2> 258164 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6806428d),segFN=segments_1,generation=1}
   [junit4]   2> 258165 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258167 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258167 WARN  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 258168 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 258169 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 258170 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 258170 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 258174 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
   [junit4]   2> 258174 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258175 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60967_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1/data/
   [junit4]   2> 258175 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 258176 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258176 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258177 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 258177 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 258177 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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=12, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7650192623566264]
   [junit4]   2> 258178 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6806428d),segFN=segments_1,generation=1}
   [junit4]   2> 258178 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 258178 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7753b578[collection1] main]
   [junit4]   2> 258179 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258179 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258179 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258180 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258181 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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> 258181 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ 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 1537674451747864576
   [junit4]   2> 258182 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1537674451747864576
   [junit4]   2> 258182 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 258183 INFO  (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 258183 INFO  (searcherExecutor-423-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7753b578[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 258184 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ 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:60967 collection:control_collection shard:shard1
   [junit4]   2> 258184 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ 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> 258186 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ 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/96104653177028612-core_node1-n_0000000000
   [junit4]   2> 258186 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000
   [junit4]   2> 258187 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 258187 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ 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> 258187 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60967/collection1/
   [junit4]   2> 258187 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 258187 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60967/collection1/ has no replicas
   [junit4]   2> 258187 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] 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> 258188 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 258189 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/96104653177028612-core_node1-n_0000000000
   [junit4]   2> 258190 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ 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:60967/collection1/ shard1
   [junit4]   2> 258191 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] 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:60967",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 258293 INFO  (zkCallback-180-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] 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> 258384 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:60967/collection1/ and leader is http://127.0.0.1:60967/collection1/
   [junit4]   2> 258384 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ 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:60967
   [junit4]   2> 258384 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 258384 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 258384 INFO  (coreZkRegister-416-thread-1-processing-n:127.0.0.1:60967_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60967_ 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> 258392 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] 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:60967",
   [junit4]   2>   "node_name":"127.0.0.1:60967_",
   [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> 258392 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] 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:60967",
   [junit4]   2>   "node_name":"127.0.0.1:60967_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 258486 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:58662/solr
   [junit4]   2> 258487 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 258494 INFO  (zkCallback-180-thread-2-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] 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> 258496 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 258498 INFO  (zkCallback-184-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ee23908 name:ZooKeeperConnection Watcher:127.0.0.1:58662/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 258498 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 258498 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 258498 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 258498 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 258498 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 258499 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 258558 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/cores/collection1
   [junit4]   2> 258559 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001
   [junit4]   2> 258560 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 258564 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@45875388{/,null,AVAILABLE}
   [junit4]   2> 258565 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.e.j.s.ServerConnector Started ServerConnector@2254da9f{HTTP/1.1,[http/1.1]}{127.0.0.1:42627}
   [junit4]   2> 258565 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.e.j.s.Server Started @262032ms
   [junit4]   2> 258565 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=42627, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/cores}
   [junit4]   2> 258565 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 258565 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001'
   [junit4]   2> 258565 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 258565 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 258566 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 258566 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 258573 INFO  (zkCallback-185-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d781b68 name:ZooKeeperConnection Watcher:127.0.0.1:58662/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 258573 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 258574 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 258576 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 258576 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/solr.xml
   [junit4]   2> 258580 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/cores
   [junit4]   2> 258580 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.CoreContainer New CoreContainer 553688482
   [junit4]   2> 258580 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001]
   [junit4]   2> 258580 WARN  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/lib
   [junit4]   2> 258580 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] 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> 258581 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 258581 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 258581 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 258581 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58662/solr
   [junit4]   2> 258581 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 258581 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 258584 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 258591 INFO  (zkCallback-188-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68aa53fc name:ZooKeeperConnection Watcher:127.0.0.1:58662 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 258595 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 258596 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 258605 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$18/1514911253@eef5de1
   [junit4]   2> 258605 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 258616 INFO  (zkCallback-189-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4fa71f28 name:ZooKeeperConnection Watcher:127.0.0.1:58662/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 258616 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 258620 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 258621 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 258621 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 258625 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 258625 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96104653177028616-127.0.0.1:42627_-n_0000000001
   [junit4]   2> 258625 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96104653177028612-127.0.0.1:60967_-n_0000000000 to know if I could be the leader
   [junit4]   2> 258625 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.ZkController Publish node=127.0.0.1:42627_ as DOWN
   [junit4]   2> 258626 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:42627_"} current state version: 3
   [junit4]   2> 258627 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:42627_
   [junit4]   2> 258728 INFO  (zkCallback-189-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_    ] 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> 258728 INFO  (zkCallback-184-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 258729 INFO  (zkCallback-180-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] 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> 259626 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42627_
   [junit4]   2> 259627 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:42627_
   [junit4]   2> 259628 INFO  (zkCallback-180-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] 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> 259628 INFO  (zkCallback-184-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> 259629 INFO  (zkCallback-184-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 259629 INFO  (zkCallback-180-thread-1-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 259629 INFO  (zkCallback-189-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_    ] 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> 259629 INFO  (zkCallback-189-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 259639 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 259640 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 259640 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/cores
   [junit4]   2> 259641 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 259641 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/cores/collection1
   [junit4]   2> 259641 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 259642 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 259643 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 259643 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] 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:42627",
   [junit4]   2>   "node_name":"127.0.0.1:42627_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"} current state version: 4
   [junit4]   2> 259643 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:42627",
   [junit4]   2>   "node_name":"127.0.0.1:42627_",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 259644 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 259644 INFO  (OverseerStateUpdate-96104653177028612-127.0.0.1:60967_-n_0000000000) [n:127.0.0.1:60967_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 259645 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.ZkController Collection collection1 not visible yet, but flagging it so a watch is registered when it becomes visible
   [junit4]   2> 259645 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [collection1]
   [junit4]   2> 259649 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2
   [junit4]   2> 259649 INFO  (TEST-TestNamedUpdateProcessors.test-seed#[E7B830774568D30B]) [n:127.0.0.1:42627_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 259649 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 259745 INFO  (zkCallback-184-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 259746 INFO  (zkCallback-189-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 259746 INFO  (zkCallback-180-thread-2-processing-n:127.0.0.1:60967_) [n:127.0.0.1:60967_    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2])
   [junit4]   2> 260651 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 260651 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
   [junit4]   2> 260651 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 260651 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1]
   [junit4]   2> 260653 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 260653 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.update.processor.TestNamedUpdateProcessors_E7B830774568D30B-001/shard-1-001/cores/collection1'
   [junit4]   2> 260654 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 260654 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 260657 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 260662 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 260665 WARN  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 260665 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.2.0
   [junit4]   2> 260675 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 260679 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 260744 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 260749 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 260750 INFO  (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:42627_) [n:127.0.0.1:42627_ c:collection1   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 260756 INFO  (co

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

g-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/analysis/icu/lucene-analyzers-icu-6.2.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/contrib/analysis-extras/lib/icu4j-56.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.4.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J1-20160620_163249_927.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J1-20160620_163249_927.suites -stdin
   [junit4] ERROR: JVM J1 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1501)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
   [junit4] 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4] 	at java.lang.Thread.run(Thread.java:745)

BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:530: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1427: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs.

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




Mime
View raw message