Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.1/5/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=54476, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=54476, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:55115/_/gv: collection already exists: awholynewstresscollection_collection5_4 at __randomizedtesting.SeedInfo.seed([6E65AD8266D76E2D]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload Error Message: expected:<[{indexVersion=1465885362501,generation=2,filelist=[_2.fdt, _2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_Lucene50_0.doc, _2_Lucene50_0.tim, _2_Lucene50_0.tip, _3.fdt, _3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_Lucene50_0.doc, _3_Lucene50_0.tim, _3_Lucene50_0.tip, _4.fdt, _4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_Lucene50_0.doc, _4_Lucene50_0.tim, _4_Lucene50_0.tip, _5.cfe, _5.cfs, _5.si, _6.fdt, _6.fdx, _6.fnm, _6.nvd, _6.nvm, _6.si, _6_Lucene50_0.doc, _6_Lucene50_0.tim, _6_Lucene50_0.tip, segments_2]}]> but was:<[{indexVersion=1465885362501,generation=2,filelist=[_2.fdt, _2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_Lucene50_0.doc, _2_Lucene50_0.tim, _2_Lucene50_0.tip, _3.fdt, _3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_Lucene50_0.doc, _3_Lucene50_0.tim, _3_Lucene50_0.tip, _4.fdt, _4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_Lucene50_0.doc, _4_Lucene50_0.tim, _4_Lucene50_0.tip, _5.cfe, _5.cfs, _5.si, _6.fdt, _6.fdx, _6.fnm, _6.nvd, _6.nvm, _6.si, _6_Lucene50_0.doc, _6_Lucene50_0.tim, _6_Lucene50_0.tip, segments_2]}, {indexVersion=1465885362501,generation=3,filelist=[_3.fdt, _3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_Lucene50_0.doc, _3_Lucene50_0.tim, _3_Lucene50_0.tip, _5.cfe, _5.cfs, _5.si, _7.cfe, _7.cfs, _7.si, segments_3]}]> Stack Trace: java.lang.AssertionError: expected:<[{indexVersion=1465885362501,generation=2,filelist=[_2.fdt, _2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_Lucene50_0.doc, _2_Lucene50_0.tim, _2_Lucene50_0.tip, _3.fdt, _3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_Lucene50_0.doc, _3_Lucene50_0.tim, _3_Lucene50_0.tip, _4.fdt, _4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_Lucene50_0.doc, _4_Lucene50_0.tim, _4_Lucene50_0.tip, _5.cfe, _5.cfs, _5.si, _6.fdt, _6.fdx, _6.fnm, _6.nvd, _6.nvm, _6.si, _6_Lucene50_0.doc, _6_Lucene50_0.tim, _6_Lucene50_0.tip, segments_2]}]> but was:<[{indexVersion=1465885362501,generation=2,filelist=[_2.fdt, _2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_Lucene50_0.doc, _2_Lucene50_0.tim, _2_Lucene50_0.tip, _3.fdt, _3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_Lucene50_0.doc, _3_Lucene50_0.tim, _3_Lucene50_0.tip, _4.fdt, _4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_Lucene50_0.doc, _4_Lucene50_0.tim, _4_Lucene50_0.tip, _5.cfe, _5.cfs, _5.si, _6.fdt, _6.fdx, _6.fnm, _6.nvd, _6.nvm, _6.si, _6_Lucene50_0.doc, _6_Lucene50_0.tim, _6_Lucene50_0.tip, segments_2]}, {indexVersion=1465885362501,generation=3,filelist=[_3.fdt, _3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_Lucene50_0.doc, _3_Lucene50_0.tim, _3_Lucene50_0.tip, _5.cfe, _5.cfs, _5.si, _7.cfe, _7.cfs, _7.si, segments_3]}]> at __randomizedtesting.SeedInfo.seed([6E65AD8266D76E2D:4BB2B6B2169F602E]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:147) at org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1227) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Timeout occured while waiting response from server at: https://127.0.0.1:41395 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:41395 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:601) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:399) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:457) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:179) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at sun.security.ssl.InputRecord.readFully(InputRecord.java:465) at sun.security.ssl.InputRecord.read(InputRecord.java:503) at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:973) at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:930) at sun.security.ssl.AppInputStream.read(AppInputStream.java:105) 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:495) ... 47 more Build Log: [...truncated 11670 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/init-core-data-001 [junit4] 2> 1032575 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[6E65AD8266D76E2D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1032576 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[6E65AD8266D76E2D]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/gv [junit4] 2> 1032578 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1032578 INFO (Thread-48984) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1032579 INFO (Thread-48984) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1032679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.ZkTestServer start zk server on port:51558 [junit4] 2> 1032679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1032680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1032682 INFO (zkCallback-1227-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28cef913 name:ZooKeeperConnection Watcher:127.0.0.1:51558 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1032682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1032683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1032683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1032685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1032692 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1032697 INFO (zkCallback-1228-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@264f3825 name:ZooKeeperConnection Watcher:127.0.0.1:51558/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1032697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1032697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1032697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1032698 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1032699 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1032700 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1032701 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1032701 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1032703 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1032703 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1032705 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1032705 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1032705 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1032705 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1032706 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1032706 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1032707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1032707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1032707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1032707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1032708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1032708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1032709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1032709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1032709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1032709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1032710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1032710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1032711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1032713 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1032713 INFO (zkCallback-1230-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6446d46 name:ZooKeeperConnection Watcher:127.0.0.1:51558/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1032713 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1032714 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1032786 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1 [junit4] 2> 1032788 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1032794 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22373efc{/_/gv,null,AVAILABLE} [junit4] 2> 1032797 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3c3b9c97{SSL,[ssl, http/1.1]}{127.0.0.1:55115} [junit4] 2> 1032797 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.e.j.s.Server Started @1036122ms [junit4] 2> 1032797 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/tempDir-001/control/data, hostContext=/_/gv, hostPort=55115, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores} [junit4] 2> 1032798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1032798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001' [junit4] 2> 1032798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1032798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1032798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1032799 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1032809 INFO (zkCallback-1231-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5cce2e41 name:ZooKeeperConnection Watcher:127.0.0.1:51558/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1032809 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1032810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1032810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1032810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/solr.xml [junit4] 2> 1032814 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores [junit4] 2> 1032814 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.CoreContainer New CoreContainer 1501039882 [junit4] 2> 1032814 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001] [junit4] 2> 1032814 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/lib [junit4] 2> 1032814 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] 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> 1032815 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1032815 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1032815 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1032815 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51558/solr [junit4] 2> 1032815 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1032815 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1032816 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1032816 INFO (zkCallback-1234-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3e27df8f name:ZooKeeperConnection Watcher:127.0.0.1:51558 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1032817 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1032817 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1032818 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$16/490759877@74ceef54 [junit4] 2> 1032823 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1032823 INFO (zkCallback-1235-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4aa8cd7e name:ZooKeeperConnection Watcher:127.0.0.1:51558/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1032823 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1032823 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1032824 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1032825 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1032826 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1032826 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1032827 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1032827 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1032828 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1032828 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1032829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1032829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https} [junit4] 2> 1032829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1032830 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1032830 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1032831 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1032831 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000 [junit4] 2> 1032831 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55115__%2Fgv [junit4] 2> 1032831 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1032832 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.Overseer Overseer (id=96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) starting [junit4] 2> 1032832 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1032835 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1032836 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1032840 INFO (OverseerCollectionConfigSetProcessor-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1032841 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55115__%2Fgv [junit4] 2> 1032841 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:55115__%2Fgv [junit4] 2> 1032842 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1032842 INFO (zkCallback-1235-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1032848 INFO (zkCallback-1235-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1032854 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1032854 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1032854 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores [junit4] 2> 1032854 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1032855 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1 [junit4] 2> 1032855 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1032858 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1032858 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1032859 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1032859 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 1032863 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1 [junit4] 2> 1032863 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:55115__%2Fgv ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1032863 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:55115/_/gv";, [junit4] 2> "node_name":"127.0.0.1:55115__%2Fgv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1032863 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:55115/_/gv";, [junit4] 2> "node_name":"127.0.0.1:55115__%2Fgv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1032864 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1032864 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1032864 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1032864 INFO (zkCallback-1235-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv ] 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> 1033864 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1033864 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1033864 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1033864 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1033865 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1033865 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1' [junit4] 2> 1033865 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1033865 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1033866 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1033869 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1033872 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 1033880 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1033884 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1033951 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1033954 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1033955 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1033959 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1033960 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1033961 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1033962 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1033962 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1 [junit4] 2> 1033962 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1033963 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1033963 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1/data/] [junit4] 2> 1033963 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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@41a87a11 [junit4] 2> 1033963 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1/data [junit4] 2> 1033963 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1/data/index/ [junit4] 2> 1033963 WARN (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1033963 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1/data/index [junit4] 2> 1033964 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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@6bf72566 [junit4] 2> 1033964 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@243517fc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@754fa764),segFN=segments_1,generation=1} [junit4] 2> 1033964 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1033964 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1/data/ [junit4] 2> 1033965 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033967 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033967 WARN (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033968 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1033969 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1033969 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1033970 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1033972 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file [junit4] 2> 1033972 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033972 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033972 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033973 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1033973 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1033973 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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=34, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=43.2392578125, floorSegmentMB=0.47265625, forceMergeDeletesPctAllowed=7.001499315392298, segmentsPerTier=29.0, maxCFSSegmentSizeMB=0.392578125, noCFSRatio=0.7084478427265961 [junit4] 2> 1033973 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@243517fc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@754fa764),segFN=segments_1,generation=1} [junit4] 2> 1033973 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1033973 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@445e2cf0[collection1] main] [junit4] 2> 1033973 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1033974 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1033975 INFO (searcherExecutor-2916-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@445e2cf0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1033975 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033976 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033976 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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> 1033976 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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 1537091313144430592 [junit4] 2> 1033977 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv 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 1537091313144430592 [junit4] 2> 1033977 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1033978 INFO (coreLoadExecutor-2915-thread-1-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1033978 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:55115/_/gv collection:control_collection shard:shard1 [junit4] 2> 1033979 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv 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> 1033980 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv 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/96068206979973125-core_node1-n_0000000000 [junit4] 2> 1033981 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv 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=180000 [junit4] 2> 1033981 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1033982 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] 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> 1033982 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv 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> 1033982 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:55115/_/gv/collection1/ [junit4] 2> 1033982 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1033982 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:55115/_/gv/collection1/ has no replicas [junit4] 2> 1033982 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1033983 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv 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/96068206979973125-core_node1-n_0000000000 [junit4] 2> 1033985 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:55115/_/gv/collection1/ shard1 [junit4] 2> 1033988 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] 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":"https://127.0.0.1:55115/_/gv";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1034089 INFO (zkCallback-1235-thread-2-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv ] 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> 1034135 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:55115/_/gv/collection1/ and leader is https://127.0.0.1:55115/_/gv/collection1/ [junit4] 2> 1034135 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:55115/_/gv [junit4] 2> 1034135 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1034135 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1034135 INFO (coreZkRegister-2909-thread-1-processing-n:127.0.0.1:55115__%2Fgv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55115__%2Fgv 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> 1034136 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:55115/_/gv";, [junit4] 2> "node_name":"127.0.0.1:55115__%2Fgv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1034136 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:55115/_/gv";, [junit4] 2> "node_name":"127.0.0.1:55115__%2Fgv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1034238 INFO (zkCallback-1235-thread-2-processing-n:127.0.0.1:55115__%2Fgv) [n:127.0.0.1:55115__%2Fgv ] 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> 1034365 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1034366 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1034371 INFO (zkCallback-1239-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@428f6690 name:ZooKeeperConnection Watcher:127.0.0.1:51558/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1034371 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1034371 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1034371 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1034372 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https} [junit4] 2> 1034372 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1034374 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1034374 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1034374 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1034375 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1034380 INFO (zkCallback-1240-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2880dff8 name:ZooKeeperConnection Watcher:127.0.0.1:51558/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1034380 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1034380 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1034381 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 1034381 INFO (OverseerStateUpdate-96068206979973125-127.0.0.1:55115__%2Fgv-n_0000000000) [n:127.0.0.1:55115__%2Fgv ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1034448 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001/cores/collection1 [junit4] 2> 1034449 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001 [junit4] 2> 1034450 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1034456 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60c39fb0{/_/gv,null,AVAILABLE} [junit4] 2> 1034458 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1c6f1026{SSL,[ssl, http/1.1]}{127.0.0.1:48065} [junit4] 2> 1034458 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.e.j.s.Server Started @1037782ms [junit4] 2> 1034458 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/_/gv, hostPort=48065, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001/cores} [junit4] 2> 1034459 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1034459 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001' [junit4] 2> 1034460 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1034460 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1034460 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1034461 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1034464 INFO (zkCallback-1241-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78075f6b name:ZooKeeperConnection Watcher:127.0.0.1:51558/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1034464 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1034464 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1034465 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1034465 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001/solr.xml [junit4] 2> 1034468 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001/cores [junit4] 2> 1034469 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.CoreContainer New CoreContainer 1664560903 [junit4] 2> 1034469 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001] [junit4] 2> 1034469 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001/shard-1-001/lib [junit4] 2> 1034469 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] 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> 1034470 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1034470 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1034470 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1034470 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51558/solr [junit4] 2> 1034470 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1034470 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1034471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1034471 INFO (zkCallback-1244-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@154a6bea name:ZooKeeperConnection Watcher:127.0.0.1:51558 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1034472 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1034472 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1034473 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:48065__%2Fgv ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$16/490759877@35f66a6 [junit4] 2> 1034474 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[6E65AD8266D76E2D]) [n:127.0.0.1:48065__%2Fgv ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1034474 INFO (zkCallback-1245-thread-1-processing-n:127.0.0.1:48065__%2Fgv) [n:127.0.0.1:48065__%2Fgv ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@27e328a7 name:ZooKeeperConnection Watcher:127.0.0.1:515 [...truncated too long message...] it4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 54 more [junit4] 2> 5319577 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[6E65AD8266D76E2D]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_6E65AD8266D76E2D-001 [junit4] 2> Jun 14, 2016 7:19:52 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=PostingsFormat(name=Direct), range_facet_l_dv=PostingsFormat(name=Memory doPackFST= false), _version_=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=Memory doPackFST= true), a_t=PostingsFormat(name=Direct), intDefault=PostingsFormat(name=Direct), a_i1=PostingsFormat(name=Direct), id=PostingsFormat(name=Memory doPackFST= false), range_facet_i_dv=PostingsFormat(name=Direct), text=Lucene50(blocksize=128), range_facet_l=PostingsFormat(name=Direct), timestamp=PostingsFormat(name=Direct)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1275, maxMBSortInHeap=7.747440776985778, sim=ClassicSimilarity, locale=ja-JP, timezone=Etc/GMT-13 [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=146703752,total=477626368 [junit4] 2> NOTE: All tests run in this JVM: [ZkCLITest, TestXmlQParser, RecoveryAfterSoftCommitTest, TestFileDictionaryLookup, TestQuerySenderNoQuery, HdfsThreadLeakTest, HttpPartitionTest, TestFieldResource, DocumentBuilderTest, TestUpdate, SolrIndexSplitterTest, TermsComponentTest, DateRangeFieldTest, FastVectorHighlighterTest, ZkStateReaderTest, TestClassicSimilarityFactory, TestExactStatsCache, SpellCheckCollatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestSystemIdResolver, TestConfigSets, ShardRoutingTest, WordBreakSolrSpellCheckerTest, TestMiniSolrCloudClusterBase, SpellCheckComponentTest, TestOrdValues, SpellingQueryConverterTest, SpellPossibilityIteratorTest, UniqFieldsUpdateProcessorFactoryTest, TestQueryUtils, SharedFSAutoReplicaFailoverUtilsTest, TestSolrCLIRunExample, TimeZoneUtilsTest, CollectionsAPIAsyncDistributedZkTest, TermVectorComponentTest, ClusterStateUpdateTest, HdfsRecoveryZkTest, BlockJoinFacetDistribTest, FullHLLTest, RuleEngineTest, TestGraphTermsQParserPlugin, TestOnReconnectListenerSupport, TestUseDocValuesAsStored, TestTrie, CoreAdminRequestStatusTest, HdfsBasicDistributedZkTest, BasicFunctionalityTest, TestFieldCacheSort, TestSmileRequest, TestDocumentBuilder, MoreLikeThisHandlerTest, DeleteReplicaTest, DistribCursorPagingTest, DistributedQueryComponentCustomSortTest, SchemaVersionSpecificBehaviorTest, LeaderInitiatedRecoveryOnShardRestartTest, TestSortingResponseWriter, DocValuesTest, MBeansHandlerTest, ConjunctionSolrSpellCheckerTest, CheckHdfsIndexTest, NotRequiredUniqueKeyTest, SpatialFilterTest, SolrInfoMBeanTest, TestSolrDynamicMBean, ZkControllerTest, ShardRoutingCustomTest, TestHashPartitioner, LoggingHandlerTest, TestNumericTerms64, TestManagedSynonymFilterFactory, TestPartialUpdateDeduplication, FileUtilsTest, TestJsonRequest, TestQuerySenderListener, TestStandardQParsers, SolrPluginUtilsTest, SecurityConfHandlerTest, IndexBasedSpellCheckerTest, TestReqParamsAPI, CustomCollectionTest, TestRawResponseWriter, BigEndianAscendingWordSerializerTest, TestQueryTypes, CollectionsAPIDistributedZkTest, DistributedIntervalFacetingTest, PKIAuthenticationIntegrationTest, TestDynamicFieldCollectionResource, FacetPivotSmallTest, TestSimpleTrackingShardHandler, TestDefaultStatsCache, ConfigSetsAPITest, TestWordDelimiterFilterFactory, TestReloadAndDeleteDocs, TestSchemaNameResource, SuggesterFSTTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, OpenCloseCoreStressTest, TestDistributedGrouping, TestFaceting, TestRecovery, TermVectorComponentDistributedTest, TestRealTimeGet, TestStressReorder, TestReload, TestStressVersions, HardAutoCommitTest, DistributedTermsComponentTest, TestCoreContainer, SolrCoreTest, StatsComponentTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, HighlighterTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SimplePostToolTest, TestCoreDiscovery, TestExtendedDismaxParser, SuggesterTest, TestStressLucene, NoCacheHeaderTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, QueryEqualityTest, DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, CacheHeaderTest, TestSurroundQueryParser, LukeRequestHandlerTest, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, TestIndexingPerformance, TestArbitraryIndexDir, RegexBoostProcessorTest, IndexSchemaTest, TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, QueryParsingTest, BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, CSVRequestHandlerTest, TestBinaryResponseWriter, TestLFUCache, PreAnalyzedFieldTest, TestSuggestSpellingConverter, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestLRUCache, ZkNodePropsTest, UUIDFieldTest, DistributedMLTComponentTest, CursorPagingTest, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestTolerantSearch, ConnectionReuseTest, TestEmbeddedSolrServerConstructors, ActionThrottleTest, AliasIntegrationTest, AsyncMigrateRouteKeyTest, CdcrReplicationHandlerTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CollectionReloadTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DistribDocExpirationUpdateProcessorTest, DistributedQueueTest, ForceLeaderTest, LeaderFailoverAfterPartitionTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, RemoteQueryErrorTest, ReplicationFactorTest, RestartWhileUpdatingTest, SSLMigrationTest, SaslZkACLProviderTest, SolrXmlInZkTest, TestCloudBackupRestore, TestCloudPivotFacet, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestLeaderInitiatedRecoveryThread, TestReplicaProperties, TestRequestForwarding, TestSizeLimitedDistributedMap, TestStressLiveNodes, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [593/614 (3!)] on J1 in 993.18s, 1 test, 1 error <<< FAILURES! [...truncated 62 lines...] [junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/temp/junit4-J1-20160614_055109_967.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-6.1/heapdumps/java_pid2533.hprof ... [junit4] Heap dump file created [611790539 bytes in 5.252 secs] [junit4] <<< JVM J1: EOF ---- [...truncated 18 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/common-build.xml:531: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/lucene/common-build.xml:1427: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/lucene/common-build.xml:984: There were test failures: 614 suites (10 ignored), 2580 tests, 2 errors, 1 failure, 33 ignored (18 assumptions) [seed: 6E65AD8266D76E2D] Total time: 255 minutes 11 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]
