Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/894/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=14233, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=14233, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:53816: collection already exists: awholynewstresscollection_collection4_0 at __randomizedtesting.SeedInfo.seed([97DE1102D84D2B94]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.HttpPartitionTest Error Message: ObjectTracker found 3 object(s) that were not released!!! [RawDirectoryWrapper, RawDirectoryWrapper, RawDirectoryWrapper] Stack Trace: java.lang.AssertionError: ObjectTracker found 3 object(s) that were not released!!! [RawDirectoryWrapper, RawDirectoryWrapper, RawDirectoryWrapper] at __randomizedtesting.SeedInfo.seed([97DE1102D84D2B94]: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:229) at sun.reflect.GeneratedMethodAccessor43.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:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 10748 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/init-core-data-001 [junit4] 2> 1546950 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[97DE1102D84D2B94]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1546950 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[97DE1102D84D2B94]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1546954 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1546954 INFO (Thread-7664) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1546954 INFO (Thread-7664) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1547054 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.ZkTestServer start zk server on port:50964 [junit4] 2> 1547054 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1547055 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1547058 INFO (zkCallback-1721-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@40355090 name:ZooKeeperConnection Watcher:127.0.0.1:50964 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1547058 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1547059 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1547059 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1547062 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1547072 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1547078 INFO (zkCallback-1722-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1db6f600 name:ZooKeeperConnection Watcher:127.0.0.1:50964/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1547078 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1547078 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1547078 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1547081 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1547082 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1547084 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1547087 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1547087 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1547090 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1547090 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1547093 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1547094 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1547096 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1547096 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1547097 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1547098 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1547099 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1547099 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1547102 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1547102 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1547105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1547105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1547107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1547107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1547109 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1547109 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1547111 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1547111 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1547492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1 [junit4] 2> 1547494 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1547496 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@64f3f7af{/,null,AVAILABLE} [junit4] 2> 1547497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.e.j.s.ServerConnector Started ServerConnector@25cb4cc0{HTTP/1.1,[http/1.1]}{127.0.0.1:59511} [junit4] 2> 1547497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.e.j.s.Server Started @1550643ms [junit4] 2> 1547497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/tempDir-001/control/data, hostContext=/, hostPort=59511, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores} [junit4] 2> 1547497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1547497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001' [junit4] 2> 1547497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1547497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1547498 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1547500 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1547508 INFO (zkCallback-1723-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1dc0aa33 name:ZooKeeperConnection Watcher:127.0.0.1:50964/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1547508 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1547509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1547520 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1547520 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/solr.xml [junit4] 2> 1547525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores [junit4] 2> 1547526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.CoreContainer New CoreContainer 1733974655 [junit4] 2> 1547526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001] [junit4] 2> 1547526 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/lib [junit4] 2> 1547526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] 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> 1547527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1547528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1547528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1547528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50964/solr [junit4] 2> 1547528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1547528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1547537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1547548 INFO (zkCallback-1726-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7aa79a0d name:ZooKeeperConnection Watcher:127.0.0.1:50964 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1547548 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1547549 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1547552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1547553 INFO (zkCallback-1727-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@31d19590 name:ZooKeeperConnection Watcher:127.0.0.1:50964/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1547554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1547555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1547557 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1547559 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1547561 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1547563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1547564 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1547566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1547567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1547568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1547569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59511_ [junit4] 2> 1547570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59511_ [junit4] 2> 1547571 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1547572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1547573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1547574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95107023152742404-127.0.0.1:59511_-n_0000000000 [junit4] 2> 1547574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59511_ [junit4] 2> 1547574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1547576 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.Overseer Overseer (id=95107023152742404-127.0.0.1:59511_-n_0000000000) starting [junit4] 2> 1547579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1547590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1547590 INFO (OverseerCollectionConfigSetProcessor-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1547590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1547591 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1547602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1547602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1547603 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores [junit4] 2> 1547604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] 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> 1547604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1 [junit4] 2> 1547604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1547605 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1547605 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:59511_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1547605 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1547606 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1547607 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] 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:59511";, [junit4] 2> "node_name":"127.0.0.1:59511_", [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> 1547607 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1547608 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] 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:59511";, [junit4] 2> "node_name":"127.0.0.1:59511_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1547608 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1547609 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1547610 INFO (zkCallback-1727-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ ] 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> 1548608 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1548608 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1548613 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1548613 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1548615 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1548615 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1' [junit4] 2> 1548615 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1548615 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1548617 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1548623 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1548628 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1548655 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1548662 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1548783 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1548793 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1548795 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1548810 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1548812 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1548814 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1548815 WARN (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1548815 WARN (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1548815 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1548816 WARN (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1548816 WARN (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1548819 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1548819 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1548821 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1548822 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1/data/] [junit4] 2> 1548822 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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@497dfe92 [junit4] 2> 1548822 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1/data [junit4] 2> 1548822 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1/data/index/ [junit4] 2> 1548822 WARN (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1548823 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1/data/index [junit4] 2> 1548823 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@25e4cf5b [junit4] 2> 1548823 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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@791a6fdd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c81f324),segFN=segments_1,generation=1} [junit4] 2> 1548824 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1548827 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1548827 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1548827 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1548827 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1548827 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1548828 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548828 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1/data/ [junit4] 2> 1548828 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548828 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548828 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548828 WARN (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548831 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1548832 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1548833 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1548834 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1548837 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1548837 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548838 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1548842 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548842 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548844 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1548845 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1548845 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@6bb35391 [junit4] 2> 1548846 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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@791a6fdd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c81f324),segFN=segments_1,generation=1} [junit4] 2> 1548846 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1548846 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6be4007d[collection1] main [junit4] 2> 1548846 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548847 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548847 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548847 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1548847 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1548847 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548848 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548848 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548848 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1548848 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1548848 INFO (searcherExecutor-3702-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6be4007d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1548849 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548849 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548849 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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> 1548849 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ 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 1521712372428832768 [junit4] 2> 1548851 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1521712372428832768 [junit4] 2> 1548851 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1548852 INFO (coreLoadExecutor-3701-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1548852 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ 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:59511 collection:control_collection shard:shard1 [junit4] 2> 1548853 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ 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> 1548856 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ 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/95107023152742404-core_node1-n_0000000000 [junit4] 2> 1548856 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ 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> 1548858 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] 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> 1548859 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1548859 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ 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> 1548859 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59511/collection1/ [junit4] 2> 1548859 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1548859 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:59511/collection1/ has no replicas [junit4] 2> 1548863 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1548867 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1548869 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] 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:59511";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1548870 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ 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:59511/collection1/ shard1 [junit4] 2> 1548971 INFO (zkCallback-1727-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ ] 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> 1549021 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:59511/collection1/ and leader is http://127.0.0.1:59511/collection1/ [junit4] 2> 1549021 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ 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:59511 [junit4] 2> 1549021 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1549021 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1549021 INFO (coreZkRegister-3695-thread-1-processing-n:127.0.0.1:59511_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59511_ 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> 1549023 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] 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:59511";, [junit4] 2> "node_name":"127.0.0.1:59511_", [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> 1549023 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] 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:59511";, [junit4] 2> "node_name":"127.0.0.1:59511_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1549107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1549108 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1549109 INFO (zkCallback-1729-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39a676ba name:ZooKeeperConnection Watcher:127.0.0.1:50964/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1549109 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1549109 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1549109 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1549112 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1549112 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1549113 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1549113 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1549114 INFO (zkCallback-1730-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7ffa810c name:ZooKeeperConnection Watcher:127.0.0.1:50964/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1549115 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1549115 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1549121 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 2 [junit4] 2> 1549121 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1549121 INFO (zkCallback-1729-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> 1549125 INFO (zkCallback-1727-thread-1-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ ] 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> 1549229 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1549513 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores/collection1 [junit4] 2> 1549514 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001 [junit4] 2> 1549516 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1549538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2bc7b2f7{/,null,AVAILABLE} [junit4] 2> 1549538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.e.j.s.ServerConnector Started ServerConnector@38f9c918{HTTP/1.1,[http/1.1]}{127.0.0.1:49679} [junit4] 2> 1549538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.e.j.s.Server Started @1552685ms [junit4] 2> 1549538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=49679, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores} [junit4] 2> 1549539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1549539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001' [junit4] 2> 1549539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1549539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1549539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1549540 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1549546 INFO (zkCallback-1731-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ca102b9 name:ZooKeeperConnection Watcher:127.0.0.1:50964/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1549546 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1549546 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1549561 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1549561 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/solr.xml [junit4] 2> 1549566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores [junit4] 2> 1549566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.CoreContainer New CoreContainer 2105025222 [junit4] 2> 1549566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001] [junit4] 2> 1549567 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/lib [junit4] 2> 1549567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] 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> 1549568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1549569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1549569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1549569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50964/solr [junit4] 2> 1549569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1549569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1549574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1549574 INFO (zkCallback-1734-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1aaa700a name:ZooKeeperConnection Watcher:127.0.0.1:50964 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1549574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1549575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1549579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1549580 INFO (zkCallback-1735-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@596bcb0a name:ZooKeeperConnection Watcher:127.0.0.1:50964/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1549580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1549584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1550588 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49679_ [junit4] 2> 1550589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:49679_ [junit4] 2> 1550590 INFO (zkCallback-1729-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1550590 INFO (zkCallback-1727-thread-2-processing-n:127.0.0.1:59511_) [n:127.0.0.1:59511_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1550590 INFO (zkCallback-1735-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1550592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1550592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95107023152742409-127.0.0.1:49679_-n_0000000001 [junit4] 2> 1550593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95107023152742404-127.0.0.1:59511_-n_0000000000 to know if I could be the leader [junit4] 2> 1550603 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1550603 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1550604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores [junit4] 2> 1550605 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1550605 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores/collection1 [junit4] 2> 1550605 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1550607 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1550607 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[97DE1102D84D2B94]) [n:127.0.0.1:49679_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1550608 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1550608 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1550609 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 1550609 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 1550609 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] 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:49679";, [junit4] 2> "node_name":"127.0.0.1:49679_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 1550609 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [0] [junit4] 2> 1550610 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1550610 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49679";, [junit4] 2> "node_name":"127.0.0.1:49679_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1550610 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 1550610 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1550712 INFO (OverseerStateUpdate-95107023152742404-127.0.0.1:59511_-n_0000000000) [n:127.0.0.1:59511_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1550713 INFO (zkCallback-1735-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1550713 INFO (zkCallback-1735-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ ] o.a.s.c.c.ZkStateReader Updating data for [collection1] from [0] to [1] [junit4] 2> 1551610 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1551610 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1551610 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1551610 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 1551611 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1551611 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores/collection1' [junit4] 2> 1551611 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1551611 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1551613 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1551617 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1551622 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1551635 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1551641 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1551768 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1551775 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1551776 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1551784 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1551787 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1551789 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1551789 WARN (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1551789 WARN (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1551790 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1551790 WARN (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1551791 WARN (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1551792 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1551792 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1551792 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1551792 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores/collection1/data/] [junit4] 2> 1551792 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 s:shard2 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@497dfe92 [junit4] 2> 1551793 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_97DE1102D84D2B94-001/shard-1-001/cores/collection1/data [junit4] 2> 1551793 INFO (coreLoadExecutor-3712-thread-1-processing-n:127.0.0.1:49679_) [n:127.0.0.1:49679_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/ [...truncated too long message...] orUtil.java:232) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) [junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:352) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:160) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:412) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:225) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:232) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) [junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:352) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:160) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:412) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:225) [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:232) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {range_facet_l_dv=BlockTreeOrds(blocksize=128), _version_=PostingsFormat(name=Memory doPackFST= false), multiDefault=PostingsFormat(name=SimpleText), a_t=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=Memory doPackFST= false), id=BlockTreeOrds(blocksize=128), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= false), text=PostingsFormat(name=Direct), range_facet_l=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Lucene54)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=is_IS, timezone=PRT [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=29813936,total=526909440 [junit4] 2> NOTE: All tests run in this JVM: [HdfsChaosMonkeyNothingIsSafeTest, OverseerCollectionConfigSetProcessorTest, OverseerTest, TestRawTransformer, TestConfigSetsAPI, BadComponentTest, CdcrReplicationDistributedZkTest, HighlighterMaxOffsetTest, SSLMigrationTest, TestRandomCollapseQParserPlugin, LukeRequestHandlerTest, SmileWriterTest, RegexBoostProcessorTest, TestRecovery, TestShortCircuitedRequests, HardAutoCommitTest, TestCollationField, TestElisionMultitermQuery, TestIndexingPerformance, DirectSolrConnectionTest, TestSearchPerf, TestStressReorder, TestObjectReleaseTracker, BlockJoinFacetRandomTest, DistributedFacetPivotSmallAdvancedTest, TestInitQParser, TestSolrJ, ReplicationFactorTest, SolrTestCaseJ4Test, TestScoreJoinQPNoScore, HdfsNNFailoverTest, TestSchemaResource, SliceStateTest, BasicDistributedZk2Test, DateMathParserTest, OverseerTaskQueueTest, ScriptEngineTest, StatsComponentTest, StatelessScriptUpdateProcessorFactoryTest, TestChildDocTransformer, AddBlockUpdateTest, DeleteInactiveReplicaTest, TestManagedSchemaFieldTypeResource, TestBlobHandler, TestSuggestSpellingConverter, HighlighterConfigTest, DataDrivenBlockJoinTest, TestStressRecovery, VMParamsZkACLAndCredentialsProvidersTest, TestHighFrequencyDictionaryFactory, TestUtils, CdcrVersionReplicationTest, TestPKIAuthenticationPlugin, TestRecoveryHdfs, TestUniqueKeyFieldResource, TestCollapseQParserPlugin, CircularListTest, TestJsonFacets, TestFieldCollectionResource, TestPerFieldSimilarity, XmlUpdateRequestHandlerTest, TestSolrQueryParser, TestCryptoKeys, TestPivotHelperCode, TestReload, TestHdfsUpdateLog, TestTolerantSearch, TestConfigOverlay, DistributedQueryComponentCustomSortTest, TestSimpleQParserPlugin, TestFieldTypeResource, TestWriterPerf, CoreAdminHandlerTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestDefaultSearchFieldResource, SampleTest, SecurityConfHandlerTest, UnloadDistributedZkTest, BadCopyFieldTest, TestNamedUpdateProcessors, TestClusterStateMutator, TestJmxIntegration, TestExactSharedStatsCache, TestFastWriter, SolrCoreCheckLockOnStartupTest, ActionThrottleTest, AsyncCallRequestStatusResponseTest, CursorMarkTest, PluginInfoTest, NoCacheHeaderTest, TestCSVResponseWriter, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, TestGroupingSearch, TestFiltering, SuggesterFSTTest, SuggesterTSTTest, SpatialFilterTest, TestCSVLoader, WordBreakSolrSpellCheckerTest, TestPseudoReturnFields, DirectUpdateHandlerOptimizeTest, TestRemoteStreaming, TestSolrDeletionPolicy1, StandardRequestHandlerTest, PrimitiveFieldTypeTest, RequestHandlersTest, PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, LoggingHandlerTest, QueryParsingTest, JsonLoaderTest, SearchHandlerTest, UpdateParamsTest, TestSolrIndexConfig, SolrIndexConfigTest, OutputWriterTest, TestPostingsSolrHighlighter, TestCharFilters, TestCodecSupport, TestXIncludeConfig, TestDFRSimilarityFactory, TestJmxMonitoredMap, OpenExchangeRatesOrgProviderTest, PreAnalyzedFieldTest, PrimUtilsTest, ClusterStateTest, DistributedIntervalFacetingTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CollectionStateFormat2Test, CollectionsAPIAsyncDistributedZkTest, ConfigSetsAPITest, CustomCollectionTest, HttpPartitionTest] [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=HttpPartitionTest -Dtests.seed=97DE1102D84D2B94 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=is_IS -Dtests.timezone=PRT -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.00s J2 | HttpPartitionTest (suite) <<< [junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 3 object(s) that were not released!!! [RawDirectoryWrapper, RawDirectoryWrapper, RawDirectoryWrapper] [junit4] > at __randomizedtesting.SeedInfo.seed([97DE1102D84D2B94]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:229) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] Completed [437/571 (2!)] on J2 in 175.03s, 1 test, 1 failure <<< FAILURES! [...truncated 453 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:791: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:728: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1436: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:991: There were test failures: 571 suites (8 ignored), 2276 tests, 1 suite-level error, 1 error, 33 ignored (18 assumptions) [seed: 97DE1102D84D2B94] Total time: 208 minutes 32 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
