Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/951/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=17337, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=17337, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:36724/jwm: collection already exists: awholynewstresscollection_collection5_4 at __randomizedtesting.SeedInfo.seed([2FF3B7F3778B0CDC]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:973) FAILED: org.apache.solr.cloud.UnloadDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=39022, name=testExecutor-5149-thread-6, state=RUNNABLE, group=TGRP-UnloadDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=39022, name=testExecutor-5149-thread-6, state=RUNNABLE, group=TGRP-UnloadDistributedZkTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:48970 at __randomizedtesting.SeedInfo.seed([2FF3B7F3778B0CDC]:0) at org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$3(BasicDistributedZkTest.java:583) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$6(ExecutorUtil.java:229) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:48970 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.BasicDistributedZkTest.lambda$createCores$3(BasicDistributedZkTest.java:581) ... 4 more 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) ... 8 more FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest Error Message: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog] Stack Trace: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog] at __randomizedtesting.SeedInfo.seed([2FF3B7F3778B0CDC]: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 11804 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/init-core-data-001 [junit4] 2> 1493492 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[2FF3B7F3778B0CDC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1493492 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[2FF3B7F3778B0CDC]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /jwm/ [junit4] 2> 1493495 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1493496 INFO (Thread-10626) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1493496 INFO (Thread-10626) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1493596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.ZkTestServer start zk server on port:35197 [junit4] 2> 1493596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1493597 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1493602 INFO (zkCallback-1554-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2f99a96c name:ZooKeeperConnection Watcher:127.0.0.1:35197 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1493602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1493602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1493602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1493606 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1493610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1493614 INFO (zkCallback-1555-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@67d96ce3 name:ZooKeeperConnection Watcher:127.0.0.1:35197/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1493614 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1493615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1493615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1493618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1493619 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1493620 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1493621 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1493621 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1493623 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1493623 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1493625 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1493625 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1493626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1493627 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1493628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1493628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1493629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1493629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1493630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1493631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1493632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1493632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1493633 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1493633 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1493634 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1493634 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1493636 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1493636 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1493638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1493641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1493642 INFO (zkCallback-1556-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66cb73aa name:ZooKeeperConnection Watcher:127.0.0.1:35197 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1493642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1493642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1493642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1493644 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1493644 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1493645 INFO (zkCallback-1557-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3e1b566d name:ZooKeeperConnection Watcher:127.0.0.1:35197/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1493645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1493645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1493645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 1493646 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 1493648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 1493648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 1493650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 1493650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 1493654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1493654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1493656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 1493656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 1493658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 1493658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 1493659 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 1493660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 1493662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 1493662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 1493666 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1493666 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1493667 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 1493668 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 1493669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 1493669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 1493671 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 1493671 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 1493790 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1 [junit4] 2> 1493803 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1493828 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1923c280{/jwm,null,AVAILABLE} [junit4] 2> 1493829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@8816dc5{HTTP/1.1,[http/1.1]}{127.0.0.1:36724} [junit4] 2> 1493829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.e.j.s.Server Started @1497272ms [junit4] 2> 1493829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/tempDir-001/control/data, hostContext=/jwm, hostPort=36724, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores} [junit4] 2> 1493830 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1493837 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001' [junit4] 2> 1493837 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1493837 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1493837 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1493838 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1493842 INFO (zkCallback-1558-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4868545f name:ZooKeeperConnection Watcher:127.0.0.1:35197/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1493843 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1493843 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1493844 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1493844 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/solr.xml [junit4] 2> 1493864 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores [junit4] 2> 1493865 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.CoreContainer New CoreContainer 856297020 [junit4] 2> 1493865 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001] [junit4] 2> 1493865 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/lib [junit4] 2> 1493866 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] 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> 1493867 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1493868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1493868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1493868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35197/solr [junit4] 2> 1493868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1493868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1493869 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1493870 INFO (zkCallback-1561-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@557e28bc name:ZooKeeperConnection Watcher:127.0.0.1:35197 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1493870 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1493871 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1493875 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15347e85c3d0005, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1493882 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1493882 INFO (zkCallback-1562-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@465dfbd9 name:ZooKeeperConnection Watcher:127.0.0.1:35197/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1493882 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1493883 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1493885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1493887 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1493888 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1493890 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1493891 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1493894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1493895 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1493897 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1493898 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1493899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1493901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1493902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1493902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1493903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95499080410398726-127.0.0.1:36724_jwm-n_0000000000 [junit4] 2> 1493903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36724_jwm [junit4] 2> 1493903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1493904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.Overseer Overseer (id=95499080410398726-127.0.0.1:36724_jwm-n_0000000000) starting [junit4] 2> 1493906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1493911 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1493912 INFO (OverseerCollectionConfigSetProcessor-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1493915 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36724_jwm [junit4] 2> 1493915 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1493915 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:36724_jwm [junit4] 2> 1493918 INFO (zkCallback-1562-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm ] 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> 1493932 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1493932 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1493933 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores [junit4] 2> 1493934 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] 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> 1493934 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1 [junit4] 2> 1493934 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1493934 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1493934 INFO (zkCallback-1562-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 1493935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1 [junit4] 2> 1493935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:36724_jwm ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1493935 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1493935 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1493937 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] 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:36724/jwm";, [junit4] 2> "node_name":"127.0.0.1:36724_jwm", [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> 1493938 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1493938 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] 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:36724/jwm";, [junit4] 2> "node_name":"127.0.0.1:36724_jwm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1493938 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1493939 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1493941 INFO (zkCallback-1562-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm ] 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> 1494938 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1494939 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1494939 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1494939 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1494940 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1494940 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1' [junit4] 2> 1494940 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1494940 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1494942 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1494948 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1494951 WARN (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 1494953 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 1494966 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1494990 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1495120 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1495130 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1495131 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1495149 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1495157 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1495160 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1495161 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1495162 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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-master/solr/build/solr-core/test/J1 [junit4] 2> 1495162 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1495163 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1495163 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1/data/] [junit4] 2> 1495163 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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@2f5c739b [junit4] 2> 1495165 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1/data [junit4] 2> 1495165 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1/data/index/ [junit4] 2> 1495166 WARN (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1495166 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1/data/index [junit4] 2> 1495166 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1495167 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@77a59887 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6a494ba5),segFN=segments_1,generation=1} [junit4] 2> 1495167 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1495184 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36724_jwm 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1/data/ [junit4] 2> 1495184 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1495199 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1495199 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1495199 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1495200 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1495200 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1495200 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495200 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495200 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495200 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495200 WARN (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495202 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1495203 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1495204 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1495205 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1495208 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495209 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495214 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495214 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495215 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1495215 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1495216 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=483713581, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1495216 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@77a59887 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6a494ba5),segFN=segments_1,generation=1} [junit4] 2> 1495216 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1495216 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2689122c[collection1] main] [junit4] 2> 1495217 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495219 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495219 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495219 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1495219 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1495219 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495220 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495220 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495220 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1495220 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1495221 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495221 INFO (searcherExecutor-3447-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2689122c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1495221 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495221 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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> 1495221 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm 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 1527985288368881664 [junit4] 2> 1495224 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 3.0ms to seed version buckets with highest version 1527985288368881664 [junit4] 2> 1495224 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1495225 INFO (coreLoadExecutor-3446-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1495234 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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:36724/jwm collection:control_collection shard:shard1 [junit4] 2> 1495235 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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> 1495245 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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/95499080410398726-core_node1-n_0000000000 [junit4] 2> 1495246 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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> 1495248 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1495248 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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> 1495248 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36724/jwm/collection1/ [junit4] 2> 1495248 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1495248 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:36724/jwm/collection1/ has no replicas [junit4] 2> 1495249 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] 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> 1495250 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1495255 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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/95499080410398726-core_node1-n_0000000000 [junit4] 2> 1495258 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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:36724/jwm/collection1/ shard1 [junit4] 2> 1495258 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] 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:36724/jwm";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1495361 INFO (zkCallback-1562-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm ] 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> 1495409 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:36724/jwm/collection1/ and leader is http://127.0.0.1:36724/jwm/collection1/ [junit4] 2> 1495410 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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:36724/jwm [junit4] 2> 1495410 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1495410 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1495410 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm 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> 1495412 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] 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:36724/jwm";, [junit4] 2> "node_name":"127.0.0.1:36724_jwm", [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> 1495413 INFO (coreZkRegister-3440-thread-1-processing-n:127.0.0.1:36724_jwm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36724_jwm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 1495413 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] 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:36724/jwm";, [junit4] 2> "node_name":"127.0.0.1:36724_jwm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1495437 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1495443 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1495448 INFO (zkCallback-1564-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7fe63fe1 name:ZooKeeperConnection Watcher:127.0.0.1:35197/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1495448 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1495448 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1495448 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1495450 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1495450 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1495517 INFO (zkCallback-1562-thread-1-processing-n:127.0.0.1:36724_jwm) [n:127.0.0.1:36724_jwm ] 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> 1495517 INFO (zkCallback-1564-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> 1495567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001/cores/collection1 [junit4] 2> 1495568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001 [junit4] 2> 1495569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1495574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6daff7b6{/jwm,null,AVAILABLE} [junit4] 2> 1495574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@11a6cb63{HTTP/1.1,[http/1.1]}{127.0.0.1:52432} [junit4] 2> 1495574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.e.j.s.Server Started @1499017ms [junit4] 2> 1495575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/jwm, hostPort=52432, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001/cores} [junit4] 2> 1495575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1495575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001' [junit4] 2> 1495575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1495575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1495575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1495579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1495581 INFO (zkCallback-1565-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a2ea5b5 name:ZooKeeperConnection Watcher:127.0.0.1:35197/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1495582 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1495582 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1495584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1495584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001/solr.xml [junit4] 2> 1495590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001/cores [junit4] 2> 1495590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.CoreContainer New CoreContainer 321773676 [junit4] 2> 1495591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001] [junit4] 2> 1495591 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_2FF3B7F3778B0CDC-001/shard-1-001/lib [junit4] 2> 1495591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] 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> 1495592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1495593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1495593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1495593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35197/solr [junit4] 2> 1495593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1495593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1495594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1495599 INFO (zkCallback-1568-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b2aa8fc name:ZooKeeperConnection Watcher:127.0.0.1:35197 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1495599 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1495600 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1495610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:52432_jwm ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1495612 INFO (zkCallback-1569-thread-1-processing-n:127.0.0.1:52432_jwm) [n:127.0.0.1:52432_jwm ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6bf8c398 name:ZooKeeperConnection Watcher:127.0.0.1:35197/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1495613 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:52432_jwm ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1495621 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:52432_jwm ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1495623 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:52432_jwm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1495625 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:52432_jwm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1495626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:52432_jwm ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95499080410398730-127.0.0.1:52432_jwm-n_0000000001 [junit4] 2> 1495627 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:52432_jwm ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95499080410398726-127.0.0.1:36724_jwm-n_0000000000 to know if I could be the leader [junit4] 2> 1495627 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2FF3B7F3778B0CDC]) [n:127.0.0.1:52432_jwm ] o.a.s.c.ZkController Publish node=127.0.0.1:52432_jwm as DOWN [junit4] 2> 1495632 INFO (OverseerStateUpdate-95499080410398726-127.0.0.1:36724_jwm-n_0000000000) [n:127.0.0.1:36724_jwm ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:52432_jwm"} current state version: 3 [junit4] 2> 1495632 INFO (OverseerStateUpdate-954990804 [...truncated too long message...] nit4] 2> ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog] [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] 2> at org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:130) [junit4] 2> at org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:74) [junit4] 2> at org.apache.solr.update.HdfsUpdateLog.ensureLog(HdfsUpdateLog.java:315) [junit4] 2> at org.apache.solr.update.UpdateLog.deleteByQuery(UpdateLog.java:490) [junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.deleteByQuery(DirectUpdateHandler2.java:467) [junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processDelete(RunUpdateProcessorFactory.java:79) [junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:52) [junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalDelete(DistributedUpdateProcessor.java:941) [junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionDeleteByQuery(DistributedUpdateProcessor.java:1430) [junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doDeleteByQuery(DistributedUpdateProcessor.java:1323) [junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processDelete(DistributedUpdateProcessor.java:1138) [junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processDelete(LogUpdateProcessorFactory.java:124) [junit4] 2> at org.apache.solr.handler.loader.JavabinLoader.delete(JavabinLoader.java:159) [junit4] 2> at org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:112) [junit4] 2> at org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:54) [junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:94) [junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:69) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2036) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:649) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:457) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:229) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:184) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1668) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:375) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:517) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> Mar 05, 2016 6:13:34 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60), sim=ClassicSimilarity, locale=ga, timezone=Canada/Central [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=2,free=166031792,total=527958016 [junit4] 2> NOTE: All tests run in this JVM: [TestSmileRequest, PolyFieldTest, HdfsDirectoryFactoryTest, QueryEqualityTest, BlockJoinFacetRandomTest, TestBlendedInfixSuggestions, ResponseLogComponentTest, TestRTGBase, ConnectionReuseTest, TestDynamicLoading, TestConfigSetsAPI, CopyFieldTest, RecoveryAfterSoftCommitTest, TestMiniSolrCloudCluster, SignatureUpdateProcessorFactoryTest, DistributedFacetPivotSmallTest, TestAddFieldRealTimeGet, DocumentAnalysisRequestHandlerTest, SynonymTokenizerTest, SolrRequestParserTest, TestSerializedLuceneMatchVersion, TestReplicationHandler, RAMDirectoryFactoryTest, TestFoldingMultitermQuery, AnalysisAfterCoreReloadTest, LeaderInitiatedRecoveryOnShardRestartTest, CachingDirectoryFactoryTest, TestCloudSchemaless, TestSortByMinMaxFunction, HdfsSyncSliceTest, TestFieldTypeResource, TestArbitraryIndexDir, BasicDistributedZkTest, TestChildDocTransformer, TestTrackingShardHandlerFactory, TestObjectReleaseTracker, TestStressLucene, TestMaxScoreQueryParser, TestLRUCache, FileBasedSpellCheckerTest, TestPhraseSuggestions, JSONWriterTest, OutputWriterTest, AddBlockUpdateTest, TestDistributedSearch, TestFaceting, RestartWhileUpdatingTest, TestDefaultSearchFieldResource, TestJsonFacets, TestJoin, DistribCursorPagingTest, TestExclusionRuleCollectionAccess, TestInitParams, SegmentsInfoRequestHandlerTest, TestScoreJoinQPNoScore, CurrencyFieldOpenExchangeTest, TestFastOutputStream, IndexSchemaRuntimeFieldTest, TestShardHandlerFactory, MergeStrategyTest, TestSolrXml, EchoParamsTest, AsyncCallRequestStatusResponseTest, TestPseudoReturnFields, PreAnalyzedFieldTest, DisMaxRequestHandlerTest, DistributedQueueTest, TestRandomDVFaceting, TestFilteredDocIdSet, HLLSerializationTest, SaslZkACLProviderTest, TlogReplayBufferedWhileIndexingTest, TestReloadDeadlock, TestRealTimeGet, DocValuesMissingTest, CollectionTooManyReplicasTest, RequiredFieldsTest, ConcurrentDeleteAndCreateCollectionTest, ResponseHeaderTest, HdfsChaosMonkeySafeLeaderTest] [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsChaosMonkeySafeLeaderTest -Dtests.seed=2FF3B7F3778B0CDC -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=ga -Dtests.timezone=Canada/Central -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.00s J0 | HdfsChaosMonkeySafeLeaderTest (suite) <<< [junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog] [junit4] > at __randomizedtesting.SeedInfo.seed([2FF3B7F3778B0CDC]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:238) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] Completed [404/582 (3!)] on J0 in 144.11s, 1 test, 1 failure <<< FAILURES! [...truncated 599 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1014: There were test failures: 582 suites (8 ignored), 2345 tests, 1 suite-level error, 2 errors, 34 ignored (17 assumptions) [seed: 2FF3B7F3778B0CDC] Total time: 264 minutes 29 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
