Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/8/
2 tests failed. FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: Captured an uncaught exception in thread: Thread[id=127125, name=Thread-7995, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=127125, name=Thread-7995, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:50485/collection1 at __randomizedtesting.SeedInfo.seed([8DA4754F6FDA6A50]:0) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:644) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:50485/collection1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:586) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:166) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:642) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:152) at java.net.SocketInputStream.read(SocketInputStream.java:122) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482) ... 5 more FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler Error Message: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory] Stack Trace: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory] at __randomizedtesting.SeedInfo.seed([8DA4754F6FDA6A50]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11712 lines...] [junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/temp/junit4-J1-20160501_073216_170.sysout [junit4] >>> JVM J1 emitted unexpected output (verbatim) ---- [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/heapdumps/java_pid13302.hprof ... [junit4] Heap dump file created [657633200 bytes in 4.863 secs] [junit4] <<< JVM J1: EOF ---- [junit4] JVM J1: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/temp/junit4-J1-20160501_073216_170.syserr [junit4] >>> JVM J1 emitted unexpected output (verbatim) ---- [junit4] WARN: Unhandled exception in event serialization. -> java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] at java.util.Arrays.copyOfRange(Arrays.java:2694) [junit4] at java.lang.String.<init>(String.java:203) [junit4] at java.lang.StringBuilder.toString(StringBuilder.java:405) [junit4] at com.carrotsearch.ant.tasks.junit4.events.AbstractEvent.toAscii(AbstractEvent.java:115) [junit4] at com.carrotsearch.ant.tasks.junit4.events.AbstractEvent.writeBinaryProperty(AbstractEvent.java:36) [junit4] at com.carrotsearch.ant.tasks.junit4.events.AppendStdErrEvent.serialize(AppendStdErrEvent.java:30) [junit4] at com.carrotsearch.ant.tasks.junit4.events.Serializer$2.run(Serializer.java:101) [junit4] at com.carrotsearch.ant.tasks.junit4.events.Serializer$2.run(Serializer.java:96) [junit4] at java.security.AccessController.doPrivileged(Native Method) [junit4] at com.carrotsearch.ant.tasks.junit4.events.Serializer.flushQueue(Serializer.java:96) [junit4] at com.carrotsearch.ant.tasks.junit4.events.Serializer.serialize(Serializer.java:81) [junit4] at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain$3$2.write(SlaveMain.java:456) [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(AppenderAttach [junit4] ableImpl.java:66) [junit4] at org.apache.log4j.Category.callAppenders(Category.java:206) [junit4] at org.apache.log4j.Category.forcedLog(Category.java:391) [junit4] at org.apache.log4j.Category.log(Category.java:856) [junit4] at org.slf4j.impl.Log4jLoggerAdapter.info(Log4jLoggerAdapter.java:323) [junit4] at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:141) [junit4] <<< JVM J1: EOF ---- [...truncated 27 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/init-core-data-001 [junit4] 2> 1940141 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[8DA4754F6FDA6A50]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1940144 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1940144 INFO (Thread-7812) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1940145 INFO (Thread-7812) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1940244 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.ZkTestServer start zk server on port:40205 [junit4] 2> 1940245 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1940245 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1940251 INFO (zkCallback-32186-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23e9e5e1 name:ZooKeeperConnection Watcher:127.0.0.1:40205 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1940251 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1940251 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1940251 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1940253 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1940254 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1940255 INFO (zkCallback-32187-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@41751ad7 name:ZooKeeperConnection Watcher:127.0.0.1:40205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1940255 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1940255 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1940255 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1940257 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1940258 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1940259 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1940260 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1940260 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1940262 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1940262 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1940264 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1940264 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1940269 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1940269 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1940270 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1940270 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1940271 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1940271 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1940273 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1940273 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1940274 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1940274 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1940275 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1940275 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1940276 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1940277 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1940278 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1940278 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1940329 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1 [junit4] 2> 1940330 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1940332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@290026c2{/,null,AVAILABLE} [junit4] 2> 1940332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2d7d149c{HTTP/1.1}{127.0.0.1:50485} [junit4] 2> 1940332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.e.j.s.Server Started @1942217ms [junit4] 2> 1940332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores, hostPort=50485, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/tempDir-001/control/data} [junit4] 2> 1940332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 1940332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001' [junit4] 2> 1940332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1940332 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1940333 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1940333 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1940334 INFO (zkCallback-32188-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c703e0a name:ZooKeeperConnection Watcher:127.0.0.1:40205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1940334 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1940334 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1940335 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1940335 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/solr.xml [junit4] 2> 1940344 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores [junit4] 2> 1940344 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.CoreContainer New CoreContainer 2019230226 [junit4] 2> 1940344 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001] [junit4] 2> 1940344 WARN (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/lib [junit4] 2> 1940344 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] 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> 1940345 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1940345 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1940345 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1940345 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40205/solr [junit4] 2> 1940346 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1940346 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1940346 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1940347 INFO (zkCallback-32191-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cda09cf name:ZooKeeperConnection Watcher:127.0.0.1:40205 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1940348 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1940348 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1940349 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@2f7b49f2 [junit4] 2> 1940349 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1940350 INFO (zkCallback-32192-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@241d2bca name:ZooKeeperConnection Watcher:127.0.0.1:40205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1940350 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1940351 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1940353 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1940358 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1940359 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1940359 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1940360 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1940361 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1940361 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1940362 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1940362 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50485_ [junit4] 2> 1940362 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:50485_ [junit4] 2> 1940363 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1940363 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1940364 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1940364 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95819522262827012-127.0.0.1:50485_-n_0000000000 [junit4] 2> 1940364 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50485_ [junit4] 2> 1940364 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1940364 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.Overseer Overseer (id=95819522262827012-127.0.0.1:50485_-n_0000000000) starting [junit4] 2> 1940365 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1940368 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1940368 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1940369 INFO (OverseerCollectionConfigSetProcessor-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1940369 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1940378 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1940378 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1940379 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores [junit4] 2> 1940379 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {coreNodeName=, dataDir=data/, name=collection1, transient=false, schema=schema.xml, shard=, config=solrconfig.xml, collection=control_collection, configSetProperties=configsetprops.json, loadOnStartup=true} [junit4] 2> 1940379 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1 [junit4] 2> 1940379 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1940380 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2 [junit4] 2> 1940380 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:50485_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1940380 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1940380 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1940381 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 1940381 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [control_collection] [junit4] 2> 1940382 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:50485";, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:50485_", [junit4] 2> "roles":null, [junit4] 2> "shard":null, [junit4] 2> "state":"down"} current state version: 0 [junit4] 2> 1940382 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 1940382 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:50485";, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:50485_", [junit4] 2> "roles":null, [junit4] 2> "shard":null, [junit4] 2> "state":"down"} [junit4] 2> 1940382 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1940383 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1940383 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1940384 INFO (zkCallback-32192-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ ] 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> 1941384 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1941384 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1941384 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1941384 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1941385 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1941385 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1' [junit4] 2> 1941385 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1941385 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1941386 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1941391 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1941399 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1 [junit4] 2> 1941426 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1941430 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1941545 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1941546 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1941546 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1941549 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1941560 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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.5/solr/build/solr-core/test/J2 [junit4] 2> 1941560 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1941561 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1941561 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1/data/] [junit4] 2> 1941561 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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@db3cbc9 [junit4] 2> 1941561 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1/data [junit4] 2> 1941561 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1/data/index/ [junit4] 2> 1941562 WARN (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1941562 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1/data/index [junit4] 2> 1941562 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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=44, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=52.91015625, floorSegmentMB=0.2626953125, forceMergeDeletesPctAllowed=17.96263242672771, segmentsPerTier=15.0, maxCFSSegmentSizeMB=1.6318359375, noCFSRatio=1.0 [junit4] 2> 1941562 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16496e08 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ed6daa6),segFN=segments_1,generation=1} [junit4] 2> 1941562 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1941563 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:50485_ 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.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1/data/ [junit4] 2> 1941563 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1941564 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1941565 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1941565 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1941565 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1941565 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1941565 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941565 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941565 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941565 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941565 WARN (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 1941567 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1941568 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1941568 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1941569 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1941580 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /replication,/config,/admin/plugins,/schema,/admin/file,/update/json,/admin/system,/update,/admin/mbeans,/admin/threads,/update/json/docs,/admin/ping,/dump,/get,standard,/admin/luke,/admin/properties,/admin/segments,/admin/logging,/update/csv [junit4] 2> 1941581 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941581 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941581 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941582 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1941582 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1941582 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@a65bfbb [junit4] 2> 1941582 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16496e08 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ed6daa6),segFN=segments_1,generation=1} [junit4] 2> 1941583 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1941583 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6baa086c[collection1] main [junit4] 2> 1941583 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941583 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941583 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941583 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1941584 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1941584 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941584 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941584 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941584 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1941584 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1941585 INFO (searcherExecutor-9663-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6baa086c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1941585 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941585 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941585 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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> 1941585 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ 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 1533112357714132992 [junit4] 2> 1941586 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1533112357714132992 [junit4] 2> 1941586 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1941587 INFO (coreLoadExecutor-9662-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1941587 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ 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:50485 collection:control_collection shard:shard1 [junit4] 2> 1941587 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ 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> 1941590 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ 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/95819522262827012-core_node1-n_0000000000 [junit4] 2> 1941590 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000 [junit4] 2> 1941592 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1941592 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ 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> 1941592 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] 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> 1941592 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50485/collection1/ [junit4] 2> 1941592 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1941592 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:50485/collection1/ has no replicas [junit4] 2> 1941593 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1941595 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95819522262827012-core_node1-n_0000000000 [junit4] 2> 1941596 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ 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:50485/collection1/ shard1 [junit4] 2> 1941597 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] 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:50485";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1941698 INFO (zkCallback-32192-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ ] 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> 1941747 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:50485/collection1/ and leader is http://127.0.0.1:50485/collection1/ [junit4] 2> 1941747 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ 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:50485 [junit4] 2> 1941747 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1941747 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1941747 INFO (coreZkRegister-9656-thread-1-processing-r:core_node1 x:collection1 c:control_collection n:127.0.0.1:50485_ s:shard1) [n:127.0.0.1:50485_ 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> 1941749 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:50485";, [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:50485_", [junit4] 2> "roles":null, [junit4] 2> "shard":"shard1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 1941749 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:50485";, [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:50485_", [junit4] 2> "roles":null, [junit4] 2> "shard":"shard1", [junit4] 2> "state":"active"} [junit4] 2> 1941851 INFO (zkCallback-32192-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ ] 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> 1941882 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1941882 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1941883 INFO (zkCallback-32194-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@46b26f50 name:ZooKeeperConnection Watcher:127.0.0.1:40205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1941884 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1941884 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1941884 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1941886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1941886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1941886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1941886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1941887 INFO (zkCallback-32195-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34c3910d name:ZooKeeperConnection Watcher:127.0.0.1:40205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1941887 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1941888 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1941889 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 1941889 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1941890 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x1546b590b060006, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1941950 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/cores/collection1 [junit4] 2> 1941951 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001 [junit4] 2> 1941951 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1941952 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@69783d7e{/,null,AVAILABLE} [junit4] 2> 1941953 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5d53fce5{HTTP/1.1}{127.0.0.1:57862} [junit4] 2> 1941953 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.e.j.s.Server Started @1943838ms [junit4] 2> 1941953 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/tempDir-001/jetty1, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/cores, hostPort=57862, solrconfig=solrconfig.xml} [junit4] 2> 1941953 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 1941953 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001' [junit4] 2> 1941953 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1941953 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1941954 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1941954 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1941955 INFO (zkCallback-32196-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5532841 name:ZooKeeperConnection Watcher:127.0.0.1:40205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1941955 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1941955 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1941956 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1941956 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/solr.xml [junit4] 2> 1941965 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/cores [junit4] 2> 1941966 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.CoreContainer New CoreContainer 105620840 [junit4] 2> 1941966 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001] [junit4] 2> 1941966 WARN (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/lib [junit4] 2> 1941966 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] 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> 1941967 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1941967 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1941967 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1941967 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40205/solr [junit4] 2> 1941967 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1941967 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1941967 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1941969 INFO (zkCallback-32199-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2f21e84b name:ZooKeeperConnection Watcher:127.0.0.1:40205 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1941970 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1941970 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1941971 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@2318a7fd [junit4] 2> 1941971 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1941972 INFO (zkCallback-32200-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6495989a name:ZooKeeperConnection Watcher:127.0.0.1:40205/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1941972 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1941975 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1941992 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1942979 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57862_ [junit4] 2> 1942979 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:57862_ [junit4] 2> 1942981 INFO (zkCallback-32192-thread-1-processing-n:127.0.0.1:50485_) [n:127.0.0.1:50485_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1942981 INFO (zkCallback-32194-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1942981 INFO (zkCallback-32200-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1942982 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1942982 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95819522262827017-127.0.0.1:57862_-n_0000000001 [junit4] 2> 1942983 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95819522262827012-127.0.0.1:50485_-n_0000000000 to know if I could be the leader [junit4] 2> 1942999 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1942999 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1942999 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/cores [junit4] 2> 1943000 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {transient=false, dataDir=data/, configSetProperties=configsetprops.json, loadOnStartup=true, config=solrconfig.xml, coreNodeName=, name=collection1, shard=, schema=schema.xml, collection=collection1} [junit4] 2> 1943000 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/cores/collection1 [junit4] 2> 1943000 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1943001 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2 [junit4] 2> 1943001 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[8DA4754F6FDA6A50]) [n:127.0.0.1:57862_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1943002 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1943002 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1943003 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 1943003 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 1943003 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:57862";, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:57862_", [junit4] 2> "roles":null, [junit4] 2> "shard":null, [junit4] 2> "state":"down"} current state version: 3 [junit4] 2> 1943003 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [0] [junit4] 2> 1943004 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1943004 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ReplicaMutator Update state numShards=3 message={ [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:57862";, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:57862_", [junit4] 2> "roles":null, [junit4] 2> "shard":null, [junit4] 2> "state":"down"} [junit4] 2> 1943004 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=3 [junit4] 2> 1943004 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1943106 INFO (OverseerStateUpdate-95819522262827012-127.0.0.1:50485_-n_0000000000) [n:127.0.0.1:50485_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1943107 INFO (zkCallback-32200-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1943107 INFO (zkCallback-32200-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ ] o.a.s.c.c.ZkStateReader Updating data for [collection1] from [0] to [1] [junit4] 2> 1944004 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1944004 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1944005 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1944005 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 1944005 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1944005 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_8DA4754F6FDA6A50-001/shard-1-001/cores/collection1' [junit4] 2> 1944006 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1944006 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1944007 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1944012 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1944021 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1 [junit4] 2> 1944041 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0.0.1:57862_) [n:127.0.0.1:57862_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1944046 INFO (coreLoadExecutor-9673-thread-1-processing-n:127.0. [...truncated too long message...] lyTests-5.5/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/build/analysis/icu/lucene-analyzers-icu-5.5.1-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/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.3.2.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/temp/junit4-J1-20160501_073216_170.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/temp/junit4-J1-20160501_073216_170.suites -stdin [junit4] ERROR: JVM J1 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1501) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java: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.5/build.xml:757: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033: 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: 196 minutes 18 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
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
