Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/15/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:40763 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:40763 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588) 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.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:381) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:458) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:169) 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:996) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:971) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482) ... 47 more FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy Error Message: Could not find collection : c1 Stack Trace: org.apache.solr.common.SolrException: Could not find collection : c1 at __randomizedtesting.SeedInfo.seed([F80514FCB9EE0EC3:934AB481C0E1D3F9]:0) at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170) at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:135) at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:46) 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 com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler Error Message: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory] Stack Trace: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory] at __randomizedtesting.SeedInfo.seed([F80514FCB9EE0EC3]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:238) at sun.reflect.GeneratedMethodAccessor22.invoke(Unknown Source) 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$7.evaluate(RandomizedRunner.java:834) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11776 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/init-core-data-001 [junit4] 2> 1825153 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F80514FCB9EE0EC3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1825153 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F80514FCB9EE0EC3]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1825161 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1825162 INFO (Thread-8274) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1825162 INFO (Thread-8274) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1825262 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.ZkTestServer start zk server on port:38237 [junit4] 2> 1825262 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1825262 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1825265 INFO (zkCallback-1487-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62cf90a8 name:ZooKeeperConnection Watcher:127.0.0.1:38237 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1825265 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1825266 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1825266 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1825274 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1825283 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1825285 INFO (zkCallback-1488-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@553b10b1 name:ZooKeeperConnection Watcher:127.0.0.1:38237/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1825285 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1825285 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1825285 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1825287 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1825289 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1825291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1825295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1825295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1825298 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1825298 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1825301 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1825301 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1825303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1825303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1825305 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1825305 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1825307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1825307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1825308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1825308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1825310 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1825310 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1825311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1825312 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1825314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1825314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1825315 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1825315 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1825422 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1 [junit4] 2> 1825425 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1825427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e1d8747{/,null,AVAILABLE} [junit4] 2> 1825429 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@373ba169{HTTP/1.1,[http/1.1]}{127.0.0.1:39150} [junit4] 2> 1825429 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.e.j.s.Server Started @1828332ms [junit4] 2> 1825429 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/tempDir-001/control/data, hostContext=/, hostPort=39150, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores} [junit4] 2> 1825429 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1825429 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001' [junit4] 2> 1825429 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1825429 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1825430 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1825431 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1825433 INFO (zkCallback-1489-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2a0233e2 name:ZooKeeperConnection Watcher:127.0.0.1:38237/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1825433 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1825433 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1825435 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1825435 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/solr.xml [junit4] 2> 1825440 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores [junit4] 2> 1825440 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.CoreContainer New CoreContainer 1203123726 [junit4] 2> 1825440 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001] [junit4] 2> 1825441 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/lib [junit4] 2> 1825441 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] 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> 1825442 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1825442 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1825442 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1825443 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38237/solr [junit4] 2> 1825443 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1825443 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1825447 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1825455 INFO (zkCallback-1492-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5bc162ef name:ZooKeeperConnection Watcher:127.0.0.1:38237 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1825455 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1825455 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1825457 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1825457 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@285462db name:ZooKeeperConnection Watcher:127.0.0.1:38237/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1825457 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1825458 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1825461 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1825465 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1825467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1825470 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1825472 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1825474 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1825475 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1825476 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1825477 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1825480 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1825482 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1825484 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1825486 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1825486 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95571544354586628-127.0.0.1:39150_-n_0000000000 [junit4] 2> 1825486 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39150_ [junit4] 2> 1825487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1825493 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.Overseer Overseer (id=95571544354586628-127.0.0.1:39150_-n_0000000000) starting [junit4] 2> 1825497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1825504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1825504 INFO (OverseerCollectionConfigSetProcessor-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1825505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39150_ [junit4] 2> 1825505 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1825506 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:39150_ [junit4] 2> 1825508 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1825508 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1825509 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1825521 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1825521 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1825521 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores [junit4] 2> 1825522 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] 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> 1825522 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1 [junit4] 2> 1825522 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1825523 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2 [junit4] 2> 1825523 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:39150_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1825524 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1825524 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1825525 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] 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:39150";, [junit4] 2> "node_name":"127.0.0.1:39150_", [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> 1825525 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] 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:39150";, [junit4] 2> "node_name":"127.0.0.1:39150_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1825526 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1825526 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1825526 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1825528 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] 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> 1826527 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1826527 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1826527 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1826527 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1826528 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1826528 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1' [junit4] 2> 1826528 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1826528 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1826530 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1826536 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1826541 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 1826551 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1826557 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1826687 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1826693 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1826694 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1826705 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1826708 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1826710 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1826711 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1826713 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2 [junit4] 2> 1826713 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1826713 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1826713 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1/data/] [junit4] 2> 1826713 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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@92c731f [junit4] 2> 1826714 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1/data [junit4] 2> 1826714 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1/data/index/ [junit4] 2> 1826714 WARN (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1826714 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1/data/index [junit4] 2> 1826714 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@55063449 [junit4] 2> 1826715 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@3005eaae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26501d85),segFN=segments_1,generation=1} [junit4] 2> 1826715 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1826715 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1/data/ [junit4] 2> 1826716 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1826718 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1826718 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1826718 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1826718 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1826718 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1826718 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826719 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826719 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826719 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826719 WARN (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826721 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1826722 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1826723 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1826725 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1826733 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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/ping,/admin/threads,/update,/admin/file [junit4] 2> 1826733 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826734 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826734 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826735 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1826735 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1826735 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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=9, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=73.4384765625, floorSegmentMB=0.955078125, forceMergeDeletesPctAllowed=9.368369961914608, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.26078272286123494 [junit4] 2> 1826742 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@3005eaae lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@26501d85),segFN=segments_1,generation=1} [junit4] 2> 1826742 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1826742 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@51ba4fa0[collection1] main] [junit4] 2> 1826744 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826749 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826749 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826751 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1826751 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1826751 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826753 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826753 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826754 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1826754 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1826754 INFO (searcherExecutor-4697-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@51ba4fa0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1826755 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826755 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826755 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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> 1826755 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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 1529144711342718976 [junit4] 2> 1826757 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ 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 1529144711342718976 [junit4] 2> 1826757 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1826759 INFO (coreLoadExecutor-4696-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1826763 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ 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:39150 collection:control_collection shard:shard1 [junit4] 2> 1826764 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ 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> 1826771 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ 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/95571544354586628-core_node1-n_0000000000 [junit4] 2> 1826773 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ 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> 1826774 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1826775 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] 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> 1826775 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ 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> 1826775 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:39150/collection1/ [junit4] 2> 1826776 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1826776 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:39150/collection1/ has no replicas [junit4] 2> 1826777 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1826778 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95571544354586628-core_node1-n_0000000000 [junit4] 2> 1826780 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ 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:39150/collection1/ shard1 [junit4] 2> 1826780 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] 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:39150";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1826884 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] 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> 1826932 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:39150/collection1/ and leader is http://127.0.0.1:39150/collection1/ [junit4] 2> 1826932 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ 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:39150 [junit4] 2> 1826932 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1826932 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1826932 INFO (coreZkRegister-4690-thread-1-processing-n:127.0.0.1:39150_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39150_ 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> 1826935 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] 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:39150";, [junit4] 2> "node_name":"127.0.0.1:39150_", [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> 1826935 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] 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:39150";, [junit4] 2> "node_name":"127.0.0.1:39150_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1827025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1827027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1827027 INFO (zkCallback-1495-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f198cdb name:ZooKeeperConnection Watcher:127.0.0.1:38237/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1827027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1827028 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1827028 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1827030 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1827031 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1827038 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] 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> 1827038 INFO (zkCallback-1495-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1827166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001/cores/collection1 [junit4] 2> 1827167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001 [junit4] 2> 1827167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1827177 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22009643{/,null,AVAILABLE} [junit4] 2> 1827177 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@eb5e92{HTTP/1.1,[http/1.1]}{127.0.0.1:40763} [junit4] 2> 1827177 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.e.j.s.Server Started @1830080ms [junit4] 2> 1827177 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=40763, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001/cores} [junit4] 2> 1827180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1827180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001' [junit4] 2> 1827180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1827180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1827181 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1827191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1827191 INFO (zkCallback-1496-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@76005680 name:ZooKeeperConnection Watcher:127.0.0.1:38237/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1827191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1827192 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1827193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1827193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001/solr.xml [junit4] 2> 1827199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001/cores [junit4] 2> 1827199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.CoreContainer New CoreContainer 473951118 [junit4] 2> 1827199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001] [junit4] 2> 1827199 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001/lib [junit4] 2> 1827199 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] 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> 1827201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1827201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1827201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1827201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38237/solr [junit4] 2> 1827202 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1827202 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1827203 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1827203 INFO (zkCallback-1499-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7cd439ec name:ZooKeeperConnection Watcher:127.0.0.1:38237 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1827204 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1827204 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1827207 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1827212 INFO (zkCallback-1500-thread-1-processing-n:127.0.0.1:40763_) [n:127.0.0.1:40763_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3b5321f3 name:ZooKeeperConnection Watcher:127.0.0.1:38237/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1827212 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1827217 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1827218 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1827220 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1827220 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95571544354586632-127.0.0.1:40763_-n_0000000001 [junit4] 2> 1827221 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95571544354586628-127.0.0.1:39150_-n_0000000000 to know if I could be the leader [junit4] 2> 1827221 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.ZkController Publish node=127.0.0.1:40763_ as DOWN [junit4] 2> 1827223 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:40763_"} current state version: 3 [junit4] 2> 1827223 INFO (OverseerStateUpdate-95571544354586628-127.0.0.1:39150_-n_0000000000) [n:127.0.0.1:39150_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:40763_ [junit4] 2> 1827325 INFO (zkCallback-1495-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1827325 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] 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> 1827325 INFO (zkCallback-1500-thread-1-processing-n:127.0.0.1:40763_) [n:127.0.0.1:40763_ ] 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> 1828223 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40763_ [junit4] 2> 1828224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40763_ [junit4] 2> 1828225 INFO (zkCallback-1495-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> 1828225 INFO (zkCallback-1500-thread-1-processing-n:127.0.0.1:40763_) [n:127.0.0.1:40763_ ] 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> 1828225 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] 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> 1828226 INFO (zkCallback-1495-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1828226 INFO (zkCallback-1493-thread-1-processing-n:127.0.0.1:39150_) [n:127.0.0.1:39150_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1828226 INFO (zkCallback-1500-thread-1-processing-n:127.0.0.1:40763_) [n:127.0.0.1:40763_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1828247 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1828247 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1828247 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_F80514FCB9EE0EC3-001/shard-1-001/cores [junit4] 2> 1828249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] 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> 1828249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F80514FCB9EE0EC3]) [n:127.0.0.1:40763_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Nightly [...truncated too long message...] tate:SyncConnected type:None path:null path:null type:None [junit4] 2> 3922278 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3922278 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3922282 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 3922288 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 3922290 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 3922292 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 3922313 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 3922316 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3922333 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 3922333 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1] [junit4] 2> 3922334 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 3922334 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 3922334 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 3922335 INFO (zkCallback-3322-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 3922349 INFO (zkCallback-3322-thread-1) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 3922353 INFO (zkCallback-3322-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0]) [junit4] 2> 3922365 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 3922366 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 3922370 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher [junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$7/1402492332@1a2da3cd rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@5f2f2eec[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 3] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214) [junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> 3922371 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48405 48405 [junit4] 2> 3922690 INFO (Thread-187342) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48405 48405 [junit4] 2> 3922692 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F80514FCB9EE0EC3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh [junit4] 2> 3922693 INFO (SUITE-ZkStateReaderTest-seed#[F80514FCB9EE0EC3]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.overseer.ZkStateReaderTest_F80514FCB9EE0EC3-001 [junit4] 2> Mar 18, 2016 1:35:33 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60), sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=pt, timezone=NZ-CHAT [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=275765536,total=506986496 [junit4] 2> NOTE: All tests run in this JVM: [CdcrReplicationHandlerTest, SimpleMLTQParserTest, OverseerStatusTest, TestQueryTypes, TestSolrCLIRunExample, DataDrivenBlockJoinTest, TestSQLHandler, TestIntervalFaceting, RequestLoggingTest, SimplePostToolTest, TestUseDocValuesAsStored, IndexBasedSpellCheckerTest, TestSystemIdResolver, TestQuerySenderNoQuery, TestSolrQueryParserDefaultOperatorResource, TestCodecSupport, HighlighterTest, DistributedTermsComponentTest, DirectSolrSpellCheckerTest, AnalyticsQueryTest, RemoteQueryErrorTest, SolrPluginUtilsTest, TestRequestForwarding, RollingRestartTest, TestTrie, DistribDocExpirationUpdateProcessorTest, ZkCLITest, TestBlendedInfixSuggestions, OverriddenZkACLAndCredentialsProvidersTest, DistributedFacetPivotLongTailTest, TestSchemaSimilarityResource, AliasIntegrationTest, ChaosMonkeySafeLeaderTest, TestDefaultSearchFieldResource, DistributedIntervalFacetingTest, HdfsWriteToMultipleCollectionsTest, HdfsCollectionsAPIDistributedZkTest, BlockJoinFacetSimpleTest, TestFieldTypeResource, ShardRoutingCustomTest, NotRequiredUniqueKeyTest, FastVectorHighlighterTest, CSVRequestHandlerTest, RankQueryTest, ResponseHeaderTest, SpellingQueryConverterTest, TestFastLRUCache, MinimalSchemaTest, TestBM25SimilarityFactory, TestHighFrequencyDictionaryFactory, WordBreakSolrSpellCheckerTest, TestFieldCollectionResource, ChangedSchemaMergeTest, TestFiltering, TestCoreContainer, TestHashQParserPlugin, TestLMJelinekMercerSimilarityFactory, SparseHLLTest, TestManagedResource, TestDistributedGrouping, ShardSplitTest, LoggingHandlerTest, TestUpdate, TestHdfsUpdateLog, HdfsBasicDistributedZkTest, TestLFUCache, SpellCheckCollatorTest, TestCursorMarkWithoutUniqueKey, MBeansHandlerTest, TestWordDelimiterFilterFactory, TestCloudManagedSchema, SpellCheckComponentTest, TestSolrDeletionPolicy2, ZkSolrClientTest, AsyncMigrateRouteKeyTest, ZkStateReaderTest] [junit4] Completed [379/581 (3!)] on J1 in 2.95s, 5 tests, 1 error <<< FAILURES! [...truncated 632 lines...] [junit4] JVM J2: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160318_123007_572.sysout [junit4] >>> JVM J2 emitted unexpected output (verbatim) ---- [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/heapdumps/java_pid14043.hprof ... [junit4] Heap dump file created [719962878 bytes in 7.735 secs] [junit4] <<< JVM J2: EOF ---- [...truncated 12 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:526: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014: There were test failures: 581 suites (10 ignored), 2327 tests, 1 suite-level error, 2 errors, 32 ignored (16 assumptions) [seed: F80514FCB9EE0EC3] Total time: 216 minutes 53 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
