Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/884/
3 tests failed. FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: Captured an uncaught exception in thread: Thread[id=68621, name=Thread-61214, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=68621, name=Thread-61214, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:51558/collection1 at __randomizedtesting.SeedInfo.seed([D7FEB351BC788D6B]:0) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:645) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:51558/collection1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:587) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:150) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:167) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:643) 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 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:480) ... 5 more FAILED: org.apache.solr.cloud.RollingRestartTest.test Error Message: Unable to restart (#4): CloudJettyRunner [url=https://127.0.0.1:45463/collection1] Stack Trace: java.lang.AssertionError: Unable to restart (#4): CloudJettyRunner [url=https://127.0.0.1:45463/collection1] at __randomizedtesting.SeedInfo.seed([D7FEB351BC788D6B:5FAA8C8B1284E093]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:104) at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53) 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:497) 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:965) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:940) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) 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:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java: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:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=2218, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=2218, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:53457/_c/gk: collection already exists: awholynewstresscollection_collection5_1 at __randomizedtesting.SeedInfo.seed([D7FEB351BC788D6B]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) Build Log: [...truncated 10010 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/init-core-data-001 [junit4] 2> 222198 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[D7FEB351BC788D6B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 222198 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[D7FEB351BC788D6B]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_c/gk [junit4] 2> 222200 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 222200 INFO (Thread-940) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 222200 INFO (Thread-940) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 222300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.ZkTestServer start zk server on port:36990 [junit4] 2> 222300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 222301 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 222304 INFO (zkCallback-156-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@318aefdc name:ZooKeeperConnection Watcher:127.0.0.1:36990 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 222304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 222304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 222304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 222307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 222307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 222308 INFO (zkCallback-157-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@76054e2d name:ZooKeeperConnection Watcher:127.0.0.1:36990/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 222308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 222308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 222308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 222310 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 222311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 222312 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 222314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 222314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 222316 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 222317 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 222318 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 222318 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 222320 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 222320 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 222321 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 222322 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 222323 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 222323 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 222324 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 222325 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 222326 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 222326 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 222327 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 222327 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 222329 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 222329 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 222330 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 222330 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 222639 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1 [junit4] 2> 222641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 222642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@64e85b64{/_c/gk,null,AVAILABLE} [junit4] 2> 222648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.e.j.s.ServerConnector Started ServerConnector@380a8af4{HTTP/1.1,[http/1.1]}{127.0.0.1:60906} [junit4] 2> 222648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.e.j.s.Server Started @225769ms [junit4] 2> 222648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/tempDir-001/control/data, hostContext=/_c/gk, hostPort=60906, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores} [junit4] 2> 222654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 222654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001' [junit4] 2> 222656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 222656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 222656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 222657 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 222658 INFO (zkCallback-158-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74f9e1b3 name:ZooKeeperConnection Watcher:127.0.0.1:36990/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 222658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 222658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 222665 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 222665 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/solr.xml [junit4] 2> 222670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores [junit4] 2> 222670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.CoreContainer New CoreContainer 593647298 [junit4] 2> 222670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001] [junit4] 2> 222670 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/lib [junit4] 2> 222671 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] 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> 222672 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 222672 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 222673 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 222673 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36990/solr [junit4] 2> 222673 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 222673 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 222673 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 222675 INFO (zkCallback-160-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4aa984d1 name:ZooKeeperConnection Watcher:127.0.0.1:36990 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 222675 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 222676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 222680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 222681 INFO (zkCallback-161-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@45b304da name:ZooKeeperConnection Watcher:127.0.0.1:36990/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 222681 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 222682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 222684 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 222687 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 222688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 222690 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 222692 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 222693 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 222694 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 222695 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 222696 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60906__c%2Fgk [junit4] 2> 222697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60906__c%2Fgk [junit4] 2> 222698 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 222699 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 222700 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 222701 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000 [junit4] 2> 222701 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60906__c%2Fgk [junit4] 2> 222701 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 222702 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.Overseer Overseer (id=95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) starting [junit4] 2> 222704 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 222709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 222709 INFO (OverseerCollectionConfigSetProcessor-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 222709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 222709 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 222735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 222735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 222736 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores [junit4] 2> 222737 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] 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> 222737 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1 [junit4] 2> 222737 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 222738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 222738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 222738 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 222738 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 222740 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60906/_c/gk";, [junit4] 2> "node_name":"127.0.0.1:60906__c%2Fgk", [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> 222740 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 222740 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60906/_c/gk";, [junit4] 2> "node_name":"127.0.0.1:60906__c%2Fgk", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 222740 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 222741 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 222742 INFO (zkCallback-161-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk ] 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> 223740 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 223740 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 223741 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 223741 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 223742 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 223742 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1' [junit4] 2> 223742 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 223742 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 223743 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 223749 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 223753 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 223771 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 223780 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 223862 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 223868 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 223869 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 223876 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 223878 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 223881 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 223881 WARN (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 223881 WARN (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 223882 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 223882 WARN (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 223882 WARN (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 223884 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 223884 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 223884 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 223884 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1/data/] [junit4] 2> 223884 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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@c79b42d [junit4] 2> 223885 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1/data [junit4] 2> 223885 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1/data/index/ [junit4] 2> 223885 WARN (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 223885 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1/data/index [junit4] 2> 223885 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19726855193967063] [junit4] 2> 223886 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@536381fb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5d1da6f2; maxCacheMB=0.2314453125 maxMergeSizeMB=0.7421875)),segFN=segments_1,generation=1} [junit4] 2> 223886 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 223886 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1/data/ [junit4] 2> 223886 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 223890 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 223890 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 223890 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 223890 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 223890 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 223890 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223890 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223890 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223891 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223891 WARN (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223892 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 223893 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 223894 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 223895 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 223898 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 223899 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223899 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223899 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223900 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 223900 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 223900 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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=8, maxMergeAtOnceExplicit=3, maxMergedSegmentMB=78.412109375, floorSegmentMB=0.728515625, forceMergeDeletesPctAllowed=20.491227892643263, segmentsPerTier=8.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6637509871190225 [junit4] 2> 223901 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@536381fb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5d1da6f2; maxCacheMB=0.2314453125 maxMergeSizeMB=0.7421875)),segFN=segments_1,generation=1} [junit4] 2> 223901 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 223901 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1c9251d8[collection1] main [junit4] 2> 223901 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223902 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223902 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223902 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 223902 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 223902 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223903 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223903 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223903 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 223903 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 223903 INFO (searcherExecutor-680-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c9251d8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 223903 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223903 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223903 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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> 223903 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk 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 1520814740722417664 [junit4] 2> 223906 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1520814740722417664 [junit4] 2> 223906 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 223906 INFO (coreLoadExecutor-679-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 223906 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:60906/_c/gk collection:control_collection shard:shard1 [junit4] 2> 223907 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk 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> 223910 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk 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/95050921183674372-core_node1-n_0000000000 [junit4] 2> 223910 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk 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> 223912 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 223912 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk 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> 223912 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60906/_c/gk/collection1/ [junit4] 2> 223912 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 223912 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] 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> 223913 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60906/_c/gk/collection1/ has no replicas [junit4] 2> 223913 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 223920 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 223921 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60906/_c/gk/collection1/ shard1 [junit4] 2> 223922 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:60906/_c/gk";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 224024 INFO (zkCallback-161-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk ] 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> 224073 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:60906/_c/gk/collection1/ and leader is http://127.0.0.1:60906/_c/gk/collection1/ [junit4] 2> 224073 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60906/_c/gk [junit4] 2> 224073 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 224073 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 224073 INFO (coreZkRegister-673-thread-1-processing-n:127.0.0.1:60906__c%2Fgk x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60906__c%2Fgk 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> 224075 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60906/_c/gk";, [junit4] 2> "node_name":"127.0.0.1:60906__c%2Fgk", [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> 224075 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60906/_c/gk";, [junit4] 2> "node_name":"127.0.0.1:60906__c%2Fgk", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 224177 INFO (zkCallback-161-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk ] 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> 224240 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 224240 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 224241 INFO (zkCallback-163-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4568b656 name:ZooKeeperConnection Watcher:127.0.0.1:36990/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 224242 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 224242 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 224242 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 224244 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 224244 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 224244 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 224244 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 224245 INFO (zkCallback-164-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f9861f name:ZooKeeperConnection Watcher:127.0.0.1:36990/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 224245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 224245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 224247 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] 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> 224247 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 224248 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x151b04f28ba0006, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 224356 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 224554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001/cores/collection1 [junit4] 2> 224555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001 [junit4] 2> 224556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 224557 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13096eef{/_c/gk,null,AVAILABLE} [junit4] 2> 224558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.e.j.s.ServerConnector Started ServerConnector@a70722d{HTTP/1.1,[http/1.1]}{127.0.0.1:40296} [junit4] 2> 224558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.e.j.s.Server Started @227679ms [junit4] 2> 224558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/_c/gk, hostPort=40296, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001/cores} [junit4] 2> 224558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 224558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001' [junit4] 2> 224558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 224558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 224558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 224559 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 224561 INFO (zkCallback-165-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2f71e0f2 name:ZooKeeperConnection Watcher:127.0.0.1:36990/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 224561 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 224561 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 224562 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 224562 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001/solr.xml [junit4] 2> 224567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001/cores [junit4] 2> 224568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.CoreContainer New CoreContainer 1292260639 [junit4] 2> 224568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001] [junit4] 2> 224568 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001/lib [junit4] 2> 224568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] 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> 224569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 224570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 224570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 224570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36990/solr [junit4] 2> 224570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 224570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 224571 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 224572 INFO (zkCallback-167-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2fe13557 name:ZooKeeperConnection Watcher:127.0.0.1:36990 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 224572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 224572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 224574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 224575 INFO (zkCallback-168-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@360ebdc1 name:ZooKeeperConnection Watcher:127.0.0.1:36990/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 224575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 224580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 225585 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40296__c%2Fgk [junit4] 2> 225586 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40296__c%2Fgk [junit4] 2> 225587 INFO (zkCallback-163-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 225587 INFO (zkCallback-161-thread-1-processing-n:127.0.0.1:60906__c%2Fgk) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 225587 INFO (zkCallback-168-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 225588 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 225589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95050921183674377-127.0.0.1:40296__c%2Fgk-n_0000000001 [junit4] 2> 225590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000 to know if I could be the leader [junit4] 2> 225601 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 225601 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 225601 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001/cores [junit4] 2> 225602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 225602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D7FEB351BC788D6B-001/shard-1-001/cores/collection1 [junit4] 2> 225602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 225603 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 225604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D7FEB351BC788D6B]) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 225604 INFO (coreLoadExecutor-690-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 225604 INFO (coreLoadExecutor-690-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 225605 INFO (coreLoadExecutor-690-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 225605 INFO (coreLoadExecutor-690-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1 [junit4] 2> 225605 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40296/_c/gk";, [junit4] 2> "node_name":"127.0.0.1:40296__c%2Fgk", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 225606 INFO (coreLoadExecutor-690-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for collection1 ver 0 [junit4] 2> 225606 INFO (coreLoadExecutor-690-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 225606 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:40296/_c/gk";, [junit4] 2> "node_name":"127.0.0.1:40296__c%2Fgk", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 225606 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 225606 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 225708 INFO (OverseerStateUpdate-95050921183674372-127.0.0.1:60906__c%2Fgk-n_0000000000) [n:127.0.0.1:60906__c%2Fgk ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 225709 INFO (zkCallback-168-thread-1-processing-n:127.0.0.1:40296__c%2Fgk) [n:127.0.0.1:40296__c%2Fgk ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json [...truncated too long message...] 00000009 [junit4] 2> 2 /solr/overseer_elect/election/95051116825935966-127.0.0.1:34015_-n_0000000042 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936044-127.0.0.1:51542_-n_0000000090 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936068-127.0.0.1:57785_-n_0000000101 [junit4] 2> 2 /solr/overseer_elect/election/95051116825935891-127.0.0.1:59298_-n_0000000021 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95051116825935888-core_node3-n_0000000001 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936140-127.0.0.1:51542_-n_0000000143 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936020-127.0.0.1:57785_-n_0000000073 [junit4] 2> 2 /solr/overseer_elect/election/95051116825935927-127.0.0.1:51138_-n_0000000145 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936116-127.0.0.1:57785_-n_0000000128 [junit4] 2> 2 /solr/overseer_elect/election/95051116825935918-127.0.0.1:55456_-n_0000000140 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936110-127.0.0.1:34015_-n_0000000124 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95051116825936140-core_node15-n_0000000045 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95051116825936044-core_node15-n_0000000027 [junit4] 2> 2 /solr/overseer_elect/election/95051116825935972-127.0.0.1:57785_-n_0000000048 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936062-127.0.0.1:34015_-n_0000000096 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936092-127.0.0.1:51542_-n_0000000115 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95051116825935975-core_node5-n_0000000016 [junit4] 2> 2 /solr/overseer_elect/election/95051116825935996-127.0.0.1:51542_-n_0000000061 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/95051116825936050-core_node14-n_0000000027 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95051116825936023-core_node5-n_0000000025 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/95051116825936098-core_node14-n_0000000034 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95051116825936071-core_node5-n_0000000034 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95051116825935996-core_node15-n_0000000018 [junit4] 2> 2 /solr/overseer_elect/election/95051116825935948-127.0.0.1:51542_-n_0000000034 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/95051116825935954-core_node14-n_0000000013 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/95051116825936002-core_node14-n_0000000020 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95051116825935948-core_node15-n_0000000009 [junit4] 2> 2 /solr/overseer_elect/election/95051116825936014-127.0.0.1:34015_-n_0000000071 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 95 /solr/collections [junit4] 2> 31 /solr/overseer/collection-queue-work [junit4] 2> 28 /solr/overseer/queue [junit4] 2> 18 /solr/live_nodes [junit4] 2> 9 /solr/overseer/queue-work [junit4] 2> [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RollingRestartTest -Dtests.method=test -Dtests.seed=D7FEB351BC788D6B -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=es_PY -Dtests.timezone=Asia/Yerevan -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 664s J2 | RollingRestartTest.test <<< [junit4] > Throwable #1: java.lang.AssertionError: Unable to restart (#4): CloudJettyRunner [url=https://127.0.0.1:45463/collection1] [junit4] > at __randomizedtesting.SeedInfo.seed([D7FEB351BC788D6B:5FAA8C8B1284E093]:0) [junit4] > at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:104) [junit4] > at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:965) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:940) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 3871884 INFO (SUITE-RollingRestartTest-seed#[D7FEB351BC788D6B]-worker) [n:127.0.0.1:51138_ c:collection1 s:shard1 r:core_node16 x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.RollingRestartTest_D7FEB351BC788D6B-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60), sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=es_PY, timezone=Asia/Yerevan [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=57849608,total=529006592 [junit4] 2> NOTE: All tests run in this JVM: [CdcrVersionReplicationTest, TestMiniSolrCloudCluster, TestMaxScoreQueryParser, DateMathParserTest, TestCharFilters, TestSolrJ, VersionInfoTest, TestReplicaProperties, CollectionTooManyReplicasTest, SuggesterTSTTest, BadComponentTest, MultiThreadedOCPTest, HdfsSyncSliceTest, BasicDistributedZk2Test, TestShortCircuitedRequests, SuggesterWFSTTest, RegexBoostProcessorTest, XmlUpdateRequestHandlerTest, TestClassNameShortening, CurrencyFieldXmlFileTest, TestSchemaSimilarityResource, SolrCloudExampleTest, ZkStateReaderTest, NoCacheHeaderTest, SharedFSAutoReplicaFailoverTest, HardAutoCommitTest, FieldAnalysisRequestHandlerTest, AutoCommitTest, TestUtils, TestSweetSpotSimilarityFactory, TestWriterPerf, TestExclusionRuleCollectionAccess, TestManagedSchemaDynamicFieldResource, CheckHdfsIndexTest, QueryParsingTest, StatelessScriptUpdateProcessorFactoryTest, TestTolerantSearch, DistributedQueryComponentOptimizationTest, TermVectorComponentDistributedTest, TestReversedWildcardFilterFactory, SpatialHeatmapFacetsTest, LeaderElectionTest, TestCoreDiscovery, ConfigSetsAPITest, TestReload, EnumFieldTest, TestRuleBasedAuthorizationPlugin, TestMinMaxOnMultiValuedField, TestTrackingShardHandlerFactory, TestSolrIndexConfig, ParsingFieldUpdateProcessorsTest, TestPerFieldSimilarityClassic, TestMiniSolrCloudClusterSSL, TestDynamicFieldCollectionResource, DeleteInactiveReplicaTest, TestStressRecovery, TestInitParams, TestRecovery, RecoveryZkTest, CoreMergeIndexesAdminHandlerTest, TestDefaultStatsCache, BitVectorTest, VMParamsZkACLAndCredentialsProvidersTest, UnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, HdfsDirectoryFactoryTest, DebugComponentTest, ClusterStateTest, BlockCacheTest, PrimitiveFieldTypeTest, MergeStrategyTest, DeleteShardTest, TestIndexingPerformance, CloudExitableDirectoryReaderTest, SimpleMLTQParserTest, SimpleFacetsTest, TestCustomDocTransformer, HighlighterConfigTest, SSLMigrationTest, RequestHandlersTest, AnalyticsMergeStrategyTest, TestOverriddenPrefixQueryForCustomFieldType, OverseerTest, DistribJoinFromCollectionTest, PKIAuthenticationIntegrationTest, SliceStateTest, TestSearchPerf, ActionThrottleTest, DistributedFacetPivotLargeTest, FieldMutatingUpdateProcessorTest, SegmentsInfoRequestHandlerTest, BadIndexSchemaTest, ForceLeaderTest, ChaosMonkeySafeLeaderTest, LeaderElectionIntegrationTest, ShardRoutingTest, TestReplicationHandler, SolrCmdDistributorTest, TestBadConfig, DistributedQueryElevationComponentTest, TestTrie, TestPseudoReturnFields, QueryEqualityTest, DocValuesMultiTest, LukeRequestHandlerTest, RequiredFieldsTest, IndexSchemaRuntimeFieldTest, TestJmxIntegration, TestAnalyzedSuggestions, SearchHandlerTest, TestLFUCache, AsyncMigrateRouteKeyTest, ConcurrentDeleteAndCreateCollectionTest, ConnectionManagerTest, DeleteReplicaTest, DistribDocExpirationUpdateProcessorTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, RollingRestartTest] [junit4] Completed [453/566 (3!)] on J2 in 664.37s, 1 test, 1 failure <<< FAILURES! [...truncated 361 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:791: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:728: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1436: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:991: There were test failures: 566 suites (8 ignored), 2254 tests, 2 errors, 1 failure, 33 ignored (18 assumptions) [seed: D7FEB351BC788D6B] Total time: 204 minutes 34 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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
