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-5.3 - Build # 11 - Still Failing
Date Tue, 12 Jan 2016 00:43:38 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/11/

2 tests failed.
FAILED:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=44631, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:37397/a_uuf/r: collection already exists: awholynewstresscollection_collection3_3
	at __randomizedtesting.SeedInfo.seed([A20E72F7075C787C]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887)


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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=23149, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:40992: Could not find collection : awholynewstresscollection_collection2_0
	at __randomizedtesting.SeedInfo.seed([A20E72F7075C787C]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)




Build Log:
[...truncated 10863 lines...]
   [junit4] JVM J2: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160111_231915_405.sysout
   [junit4] >>> JVM J2: stdout (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
   [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/heapdumps/java_pid16115.hprof ...
   [junit4] Heap dump file created [521837171 bytes in 3.392 secs]
   [junit4] <<< JVM J2: EOF ----

   [junit4] JVM J2: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160111_231915_405.syserr
   [junit4] >>> JVM J2: stderr (verbatim) ----
   [junit4] WARN: Unhandled exception in event serialization. -> java.lang.OutOfMemoryError: GC overhead limit exceeded
   [junit4] 	at java.nio.CharBuffer.wrap(CharBuffer.java:369)
   [junit4] 	at sun.nio.cs.StreamEncoder.implWrite(StreamEncoder.java:265)
   [junit4] 	at sun.nio.cs.StreamEncoder.write(StreamEncoder.java:125)
   [junit4] 	at sun.nio.cs.StreamEncoder.write(StreamEncoder.java:113)
   [junit4] 	at java.io.OutputStreamWriter.write(OutputStreamWriter.java:194)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.stream.JsonWriter.string(JsonWriter.java:535)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.stream.JsonWriter.value(JsonWriter.java:364)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.TypeAdapters$22.write(TypeAdapters.java:626)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.TypeAdapters$22.write(TypeAdapters.java:578)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.Streams.write(Streams.java:67)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.GsonToMiniGsonTypeAdapterFactory$3.write(GsonToMiniGsonTypeAdapterFactory.java:98)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.TypeAdapterRuntimeTypeWrapper.write(TypeAdapterRuntimeTypeWrapper.java:66)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$1.write(ReflectiveTypeAdapterFactory.java:82)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.internal.bind.ReflectiveTypeAdapterFactory$Adapter.write(ReflectiveTypeAdapterFactory.java:194)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.dependencies.com.google.gson.Gson.toJson(Gson.java:512)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.events.Serializer.serialize(Serializer.java:87)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain$4.write(SlaveMain.java:410)
   [junit4] 	at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
   [junit4] 	at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
   [junit4] 	at java.io.PrintStream.flush(PrintStream.java:338)
   [junit4] 	at java.io.FilterOutputStream.flush(FilterOutputStream.java:140)
   [junit4] 	at java.io.PrintStream.write(PrintStream.java:482)
   [junit4] 	at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221)
   [junit4] 	at sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291)
   [junit4] 	at sun.nio.cs.StreamEncoder.implFlush(StreamEncoder.java:295)
   [junit4] 	at sun.nio.cs.StreamEncoder.flush(StreamEncoder.java:141)
   [junit4] 	at java.io.OutputStreamWriter.flush(OutputStreamWriter.java:229)
   [junit4] 	at org.apache.log4j.helpers.QuietWriter.flush(QuietWriter.java:59)
   [junit4] 	at org.apache.log4j.WriterAppender.subAppend(WriterAppender.java:324)
   [junit4] 	at org.apache.log4j.WriterAppender.append(WriterAppender.java:162)
   [junit4] 	at org.apache.log4j.AppenderSkeleton.doAppend(AppenderSkeleton.java:251)
   [junit4] 	at org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:66)
   [junit4] <<< JVM J2: EOF ----

[...truncated 33 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/init-core-data-001
   [junit4]   2> 1795710 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[A20E72F7075C787C]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 1795710 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[A20E72F7075C787C]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /a_uuf/r
   [junit4]   2> 1795715 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1795716 INFO  (Thread-40696) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1795716 INFO  (Thread-40696) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1795816 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.ZkTestServer start zk server on port:53755
   [junit4]   2> 1795816 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1795817 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1795819 INFO  (zkCallback-795-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@734eaedf name:ZooKeeperConnection Watcher:127.0.0.1:53755 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1795819 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1795820 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1795820 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1795823 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1795823 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1795824 INFO  (zkCallback-796-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@337ccf34 name:ZooKeeperConnection Watcher:127.0.0.1:53755/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1795824 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1795824 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1795825 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1795827 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1795828 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1795830 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1795831 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1795832 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1795834 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 1795834 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1795836 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1795837 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1795838 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1795839 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1795840 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1795840 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1795842 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1795842 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1795844 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1795844 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1795846 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1795846 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1795848 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1795848 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1795849 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1795850 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1795851 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1795851 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1795853 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1795854 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1795856 INFO  (zkCallback-797-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75281822 name:ZooKeeperConnection Watcher:127.0.0.1:53755 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1795856 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1795856 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1795856 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1795858 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1795859 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1795860 INFO  (zkCallback-798-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d53fab0 name:ZooKeeperConnection Watcher:127.0.0.1:53755/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1795860 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1795861 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1795861 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 1795862 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 1795864 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 1795864 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 1795866 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 1795866 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 1795868 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1795868 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1795870 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 1795870 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 1795872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 1795872 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 1795876 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 1795876 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 1795878 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1795878 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1795880 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1795880 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1795882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 1795882 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 1795884 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 1795884 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 1795885 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 1795886 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 1795973 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1
   [junit4]   2> 1795975 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1795986 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@712f1b48{/a_uuf/r,null,AVAILABLE}
   [junit4]   2> 1795994 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.e.j.s.ServerConnector Started ServerConnector@4ba84e05{HTTP/1.1}{127.0.0.1:46173}
   [junit4]   2> 1795994 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.e.j.s.Server Started @1798914ms
   [junit4]   2> 1795994 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores, hostPort=46173, hostContext=/a_uuf/r, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/tempDir-001/control/data}
   [junit4]   2> 1795995 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1795995 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/'
   [junit4]   2> 1796010 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1796011 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1796013 INFO  (zkCallback-799-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@20dc11c8 name:ZooKeeperConnection Watcher:127.0.0.1:53755/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1796013 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1796013 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1796014 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1796014 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/solr.xml
   [junit4]   2> 1796028 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores
   [junit4]   2> 1796028 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.CoreContainer New CoreContainer 992879937
   [junit4]   2> 1796028 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/]
   [junit4]   2> 1796028 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/lib
   [junit4]   2> 1796029 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/lib).
   [junit4]   2> 1796037 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] 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> 1796038 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1796038 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1796039 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1796039 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53755/solr
   [junit4]   2> 1796039 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1796039 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1796039 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1796041 INFO  (zkCallback-801-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28c78c2 name:ZooKeeperConnection Watcher:127.0.0.1:53755 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1796042 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1796042 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1796043 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1796045 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@653f1ff0 name:ZooKeeperConnection Watcher:127.0.0.1:53755/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1796045 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1796046 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1796048 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1796050 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1796052 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1796054 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1796056 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1796058 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1796059 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1796060 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1796062 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46173_a_uuf%2Fr
   [junit4]   2> 1796062 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46173_a_uuf%2Fr
   [junit4]   2> 1796064 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1796065 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1796066 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1796067 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000
   [junit4]   2> 1796068 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46173_a_uuf%2Fr
   [junit4]   2> 1796068 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1796069 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.Overseer Overseer (id=95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) starting
   [junit4]   2> 1796071 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1796077 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1796078 INFO  (OverseerCollectionProcessor-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 1796078 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1796078 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1796094 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1796094 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1796095 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores
   [junit4]   2> 1796096 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.SolrCore Created CoreDescriptor: {collection=control_collection, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1, shard=, coreNodeName=, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/, configSetProperties=configsetprops.json, transient=false, dataDir=data/, config=solrconfig.xml, name=collection1, loadOnStartup=true, schema=schema.xml}
   [junit4]   2> 1796096 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/
   [junit4]   2> 1796096 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 1796097 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1796097 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1796097 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 1796097 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1796098 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1796098 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1796098 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "base_url":"http://127.0.0.1:46173/a_uuf/r",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:46173_a_uuf%2Fr",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "state":"down",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "core":"collection1"} current state version: 0
   [junit4]   2> 1796099 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "base_url":"http://127.0.0.1:46173/a_uuf/r",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:46173_a_uuf%2Fr",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "state":"down",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "core":"collection1"}
   [junit4]   2> 1796099 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1796099 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1796100 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] 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> 1796102 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 1797098 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1797098 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1797099 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1797099 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 1797100 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 1797100 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/'
   [junit4]   2> 1797124 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1797132 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1797151 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 1797184 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1797185 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 1797192 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1797430 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1797437 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1797439 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1797448 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1797451 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1797454 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1797455 WARN  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1797455 WARN  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1797456 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1797456 WARN  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1797457 WARN  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1797458 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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-5.3/solr/build/solr-core/test/J1
   [junit4]   2> 1797458 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1797458 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1797458 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 1797458 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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@2764c9ab
   [junit4]   2> 1797459 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/data
   [junit4]   2> 1797459 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/data/index/
   [junit4]   2> 1797459 WARN  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1797460 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/data/index
   [junit4]   2> 1797460 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=34.734375, floorSegmentMB=0.8486328125, forceMergeDeletesPctAllowed=26.680960392272002, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1797463 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/index-NIOFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@611a5a82),segFN=segments_1,generation=1}
   [junit4]   2> 1797464 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1797464 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46173_a_uuf%2Fr 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-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/data/
   [junit4]   2> 1797464 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 1797469 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1797469 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1797469 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1797469 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1797469 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1797469 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797470 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797470 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797470 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797473 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1797474 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1797475 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1797476 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1797480 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/ping,/config,/update,/admin/file,/schema,/admin/luke,/update/json,/admin/segments,/admin/properties,/admin/logging,/admin/plugins,/get,standard,/update/json/docs,/admin/threads,/admin/mbeans,/update/csv,/admin/system,/replication
   [junit4]   2> 1797481 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797482 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797482 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797483 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1797483 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1797483 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=34.4208984375, floorSegmentMB=2.10546875, forceMergeDeletesPctAllowed=2.8789603339282754, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1797484 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/index-NIOFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@611a5a82),segFN=segments_1,generation=1}
   [junit4]   2> 1797485 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1797485 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@757415e5[collection1] main
   [junit4]   2> 1797485 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797486 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797486 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797486 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1797487 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1797487 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797487 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797487 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797487 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1797488 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1797488 INFO  (searcherExecutor-2409-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@757415e5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1797488 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797488 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797489 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797489 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr 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 1523115523751018496
   [junit4]   2> 1797491 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 3 ms to seed version buckets with highest version 1523115523751018496
   [junit4]   2> 1797491 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1797491 INFO  (coreLoadExecutor-2408-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1797492 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr 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:46173/a_uuf/r collection:control_collection shard:shard1
   [junit4]   2> 1797493 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797496 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr 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/95194720118308870-core_node1-n_0000000000
   [junit4]   2> 1797497 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 1797498 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1797498 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1797499 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797499 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46173/a_uuf/r/collection1/
   [junit4]   2> 1797499 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1797499 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:46173/a_uuf/r/collection1/ has no replicas
   [junit4]   2> 1797499 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] 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> 1797499 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr 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:46173/a_uuf/r/collection1/ shard1
   [junit4]   2> 1797499 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1797503 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1797504 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] 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:46173/a_uuf/r",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 1797599 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1797600 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1797601 INFO  (zkCallback-804-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a782c10 name:ZooKeeperConnection Watcher:127.0.0.1:53755/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1797602 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1797602 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1797602 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1797605 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1797605 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1797605 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1797606 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1797607 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] 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> 1797607 INFO  (zkCallback-805-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2fa0ff65 name:ZooKeeperConnection Watcher:127.0.0.1:53755/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1797607 INFO  (zkCallback-804-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> 1797607 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1797609 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1797610 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 1797611 INFO  (zkCallback-804-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 1797612 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1797613 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 2
   [junit4]   2> 1797613 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1797615 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1797616 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 1797617 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] 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> 1797617 INFO  (zkCallback-804-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> 1797618 INFO  (zkCallback-804-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 1797618 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 1797655 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:46173/a_uuf/r/collection1/ and leader is http://127.0.0.1:46173/a_uuf/r/collection1/
   [junit4]   2> 1797655 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr 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:46173/a_uuf/r
   [junit4]   2> 1797655 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1797655 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1797655 INFO  (coreZkRegister-2402-thread-1-processing-r:core_node1 c:control_collection n:127.0.0.1:46173_a_uuf%2Fr s:shard1 x:collection1) [n:127.0.0.1:46173_a_uuf%2Fr 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> 1797656 INFO  (zkCallback-802-thread-1-processing-n:127.0.0.1:46173_a_uuf%2Fr) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 1797657 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "base_url":"http://127.0.0.1:46173/a_uuf/r",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:46173_a_uuf%2Fr",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "core":"collection1"} current state version: 3
   [junit4]   2> 1797658 INFO  (OverseerStateUpdate-95194720118308870-127.0.0.1:46173_a_uuf%2Fr-n_0000000000) [n:127.0.0.1:46173_a_uuf%2Fr    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "base_url":"http://127.0.0.1:46173/a_uuf/r",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "node_name":"127.0.0.1:46173_a_uuf%2Fr",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "core":"collection1"}
   [junit4]   2> 1797700 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001/cores/collection1
   [junit4]   2> 1797701 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001
   [junit4]   2> 1797702 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 1797704 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@42a39234{/a_uuf/r,null,AVAILABLE}
   [junit4]   2> 1797704 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.e.j.s.ServerConnector Started ServerConnector@4b586562{HTTP/1.1}{127.0.0.1:37397}
   [junit4]   2> 1797704 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.e.j.s.Server Started @1800624ms
   [junit4]   2> 1797704 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/a_uuf/r, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/tempDir-001/jetty1, hostPort=37397, solrconfig=solrconfig.xml, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001/cores}
   [junit4]   2> 1797705 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 1797705 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001/'
   [junit4]   2> 1797721 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1797722 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1797723 INFO  (zkCallback-806-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@64713ad3 name:ZooKeeperConnection Watcher:127.0.0.1:53755/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1797723 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1797723 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1797724 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1797725 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001/solr.xml
   [junit4]   2> 1797738 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001/cores
   [junit4]   2> 1797739 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.CoreContainer New CoreContainer 2076302188
   [junit4]   2> 1797739 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001/]
   [junit4]   2> 1797739 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001/lib
   [junit4]   2> 1797739 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A20E72F7075C787C-001/shard-1-001/lib).
   [junit4]   2> 1797747 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] 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> 1797748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1797749 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1797749 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1797749 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53755/solr
   [junit4]   2> 1797749 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1797749 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1797750 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1797751 INFO  (zkCallback-808-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4d7ceef6 name:ZooKeeperConnection Watcher:127.0.0.1:53755 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1797752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1797752 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1797754 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[A20E72F7075C787C]) [n:127.0.0.1:37397_a_uuf%2Fr    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1797755 INFO  (zkCallback-809-thread-1-processing-n:127.0.0.1:37397_a_uuf%2Fr) [n:127.0.0.1:37397_a_uuf%2Fr    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@732d3529 name:ZooKeeperConnectio

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

tlyTests-5.3/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/build/analysis/icu/lucene-analyzers-icu-5.3.2-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/contrib/analysis-extras/lib/icu4j-54.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.7/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.1.13.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160111_231915_405.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160111_231915_405.suites
   [junit4] ERROR: JVM J2 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:1504)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:133)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:964)
   [junit4] 	at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:961)
   [junit4] 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
   [junit4] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
   [junit4] 	at java.lang.Thread.run(Thread.java:745)

BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003: 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: 202 minutes 29 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