Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/780/
2 tests failed. REGRESSION: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=52837, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=52837, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:55410/a_/g: Could not find collection : awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([B9416C87DB895D6E]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) 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:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: KeeperErrorCode = Session expired for /clusterstate.json Stack Trace: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /clusterstate.json at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155) at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353) at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:350) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61) at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:350) at org.apache.solr.common.cloud.ZkStateReader.refreshLegacyClusterState(ZkStateReader.java:471) at org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:256) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testNoCollectionSpecified(CollectionsAPIDistributedZkTest.java:465) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:170) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) 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:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) 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:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 9937 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/init-core-data-001 [junit4] 2> 12772 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[B9416C87DB895D6E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 12778 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[B9416C87DB895D6E]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /dbg/h [junit4] 2> 12810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 12816 INFO (Thread-8) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 12816 INFO (Thread-8) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 12916 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.ZkTestServer start zk server on port:49217 [junit4] 2> 12918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 12936 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 12978 INFO (zkCallback-18-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29312ed5 name:ZooKeeperConnection Watcher:127.0.0.1:49217 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 12978 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 12979 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 12979 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 12988 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 13014 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 13025 INFO (zkCallback-19-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@37c9aac1 name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 13025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 13026 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 13027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 13031 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 13033 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 13036 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 13038 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13047 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 13058 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13065 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 13069 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13070 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 13072 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 13075 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13076 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 13079 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13079 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 13082 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13082 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 13085 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13086 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 13088 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13089 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 13092 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13092 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 13095 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 13095 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 13113 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 13133 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 13149 INFO (zkCallback-20-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78f4b888 name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 13149 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 13150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 13180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 13198 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 13224 INFO (zkCallback-21-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@584b99c8 name:ZooKeeperConnection Watcher:127.0.0.1:49217 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 13224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 13225 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 13225 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 13244 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 13273 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 13285 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@64e75d31 name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 13285 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 13286 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 13287 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/solrconfig.xml [junit4] 2> 13287 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 13291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/schema.xml [junit4] 2> 13291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 13294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/enumsConfig.xml [junit4] 2> 13294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 13297 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 13297 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 13300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/stopwords.txt [junit4] 2> 13300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 13303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/protwords.txt [junit4] 2> 13303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 13306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/currency.xml [junit4] 2> 13306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 13308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/open-exchange-rates.json [junit4] 2> 13309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 13311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 13311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 13314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/old_synonyms.txt [junit4] 2> 13314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 13316 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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/conf2/synonyms.txt [junit4] 2> 13316 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 13330 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 13330 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 13884 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1 [junit4] 2> 13930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.u.log Logging initialized @17334ms [junit4] 2> 14105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 14183 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1083523b{/dbg/h,null,AVAILABLE} [junit4] 2> 14391 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2bab355a{SSL-http/1.1}{127.0.0.1:59237} [junit4] 2> 14392 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.s.Server Started @17800ms [junit4] 2> 14392 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/tempDir-001/control/data, hostContext=/dbg/h, hostPort=59237, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores} [junit4] 2> 14401 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 14402 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/' [junit4] 2> 14423 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 14425 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 14429 INFO (zkCallback-23-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3ae7b5b4 name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 14429 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 14430 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 14432 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 14435 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/solr.xml [junit4] 2> 14452 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores [junit4] 2> 14452 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.CoreContainer New CoreContainer 1615022051 [junit4] 2> 14452 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/] [junit4] 2> 14453 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/lib [junit4] 2> 14453 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/lib). [junit4] 2> 14476 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 14500 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 14504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 14504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 14505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49217/solr [junit4] 2> 14523 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 14524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 14525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 14532 INFO (zkCallback-25-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1eacd7bc name:ZooKeeperConnection Watcher:127.0.0.1:49217 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 14532 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 14533 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 14546 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 14553 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10cda020 name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 14553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 14566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 14575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 14584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 14591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 14595 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 14607 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 14611 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 14614 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 14617 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 14620 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59237_dbg%2Fh [junit4] 2> 14621 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59237_dbg%2Fh [junit4] 2> 14627 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 14630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 14633 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 14638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000 [junit4] 2> 14642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59237_dbg%2Fh [junit4] 2> 14642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 14645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.Overseer Overseer (id=94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) starting [junit4] 2> 14652 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 14691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 14699 INFO (OverseerCollectionConfigSetProcessor-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 14701 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 14699 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 15013 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 15014 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 15016 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores [junit4] 2> 15022 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 15023 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/ [junit4] 2> 15023 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 15030 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 15031 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 15032 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 15033 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 15043 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:59237/dbg/h";, [junit4] 2> "node_name":"127.0.0.1:59237_dbg%2Fh", [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> 15043 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 15054 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:59237/dbg/h";, [junit4] 2> "node_name":"127.0.0.1:59237_dbg%2Fh", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 15057 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 15067 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 15071 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh ] 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> 16044 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 16045 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 16046 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 16046 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 16047 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 16048 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/' [junit4] 2> 16088 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 16122 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 16128 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 16166 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 16171 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 16189 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 16421 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 16443 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 16445 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 16518 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 16523 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 16526 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 16527 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 16528 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 16528 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 16529 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 16529 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 16530 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 16531 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 16531 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 16532 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 16532 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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@18bc0cfc [junit4] 2> 16539 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/data [junit4] 2> 16539 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/data/index/ [junit4] 2> 16539 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 16540 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/data/index [junit4] 2> 16540 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.25077583242819534] [junit4] 2> 16542 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@621fb739 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f16b729),segFN=segments_1,generation=1} [junit4] 2> 16542 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 16543 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/data/ [junit4] 2> 16543 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 16550 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 16562 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 16562 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 16563 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 16563 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 16563 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16564 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16564 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16564 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16567 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 16571 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 16573 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 16574 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 16586 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 16587 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16589 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16589 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16591 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 16591 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 16593 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=39, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=75.7529296875, floorSegmentMB=1.63671875, forceMergeDeletesPctAllowed=23.796455877955665, segmentsPerTier=19.0, maxCFSSegmentSizeMB=0.796875, noCFSRatio=0.0 [junit4] 2> 16594 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@621fb739 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f16b729),segFN=segments_1,generation=1} [junit4] 2> 16594 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 16594 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@128d85d7[collection1] main [junit4] 2> 16595 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16596 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16596 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16599 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 16599 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 16600 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16601 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16601 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16601 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 16601 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 16604 INFO (searcherExecutor-19-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@128d85d7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 16604 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16604 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16605 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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> 16605 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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 1510923767713366016 [junit4] 2> 16606 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh 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 1510923767713366016 [junit4] 2> 16608 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 16610 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 16611 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:59237/dbg/h collection:control_collection shard:shard1 [junit4] 2> 16622 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh 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> 16628 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh 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/94432735235407879-core_node1-n_0000000000 [junit4] 2> 16630 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 16633 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 16633 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh 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> 16633 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] 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> 16635 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:59237/dbg/h/collection1/ [junit4] 2> 16635 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 16635 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:59237/dbg/h/collection1/ has no replicas [junit4] 2> 16636 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:59237/dbg/h/collection1/ shard1 [junit4] 2> 16638 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh ] 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> 16639 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 16649 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"https://127.0.0.1:59237/dbg/h";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 16754 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh ] 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> 16798 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:59237/dbg/h/collection1/ and leader is https://127.0.0.1:59237/dbg/h/collection1/ [junit4] 2> 16799 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:59237/dbg/h [junit4] 2> 16799 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 16799 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 16799 INFO (coreZkRegister-12-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59237_dbg%2Fh 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> 16804 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:59237/dbg/h";, [junit4] 2> "node_name":"127.0.0.1:59237_dbg%2Fh", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 16807 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:59237/dbg/h";, [junit4] 2> "node_name":"127.0.0.1:59237_dbg%2Fh", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 16912 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh ] 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> 17065 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 17066 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 17069 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b2b975d name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 17069 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 17070 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 17070 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 17075 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 17075 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 17076 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 17076 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 17081 INFO (zkCallback-29-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78439c93 name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 17082 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 17082 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 17089 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] 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: 4 [junit4] 2> 17090 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 17194 INFO (OverseerStateUpdate-94432735235407879-127.0.0.1:59237_dbg%2Fh-n_0000000000) [n:127.0.0.1:59237_dbg%2Fh ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 17507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001/cores/collection1 [junit4] 2> 17509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001 [junit4] 2> 17510 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 17526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@665db566{/dbg/h,null,AVAILABLE} [junit4] 2> 17541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4ae1ecb{SSL-http/1.1}{127.0.0.1:39720} [junit4] 2> 17541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.e.j.s.Server Started @20950ms [junit4] 2> 17542 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/dbg/h, hostPort=39720, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001/cores} [junit4] 2> 17542 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 17543 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001/' [junit4] 2> 17565 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 17566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 17569 INFO (zkCallback-30-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@53eeb140 name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 17569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 17570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 17572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 17572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001/solr.xml [junit4] 2> 17580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001/cores [junit4] 2> 17580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.CoreContainer New CoreContainer 1840415695 [junit4] 2> 17580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001/] [junit4] 2> 17580 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001/lib [junit4] 2> 17581 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_B9416C87DB895D6E-001/shard-1-001/lib). [junit4] 2> 17590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] 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> 17593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 17595 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 17596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 17596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49217/solr [junit4] 2> 17596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 17596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 17602 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 17615 INFO (zkCallback-32-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@500b3746 name:ZooKeeperConnection Watcher:127.0.0.1:49217 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 17616 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 17616 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 17621 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:39720_dbg%2Fh ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 17622 INFO (zkCallback-33-thread-1-processing-n:127.0.0.1:39720_dbg%2Fh) [n:127.0.0.1:39720_dbg%2Fh ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@73cc3c3b name:ZooKeeperConnection Watcher:127.0.0.1:49217/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 17622 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:39720_dbg%2Fh ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 17630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:39720_dbg%2Fh ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 18645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:39720_dbg%2Fh ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39720_dbg%2Fh [junit4] 2> 18649 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:39720_dbg%2Fh ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:39720_dbg%2Fh [junit4] 2> 18652 INFO (zkCallback-28-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> 18652 INFO (zkCallback-33-thread-1-processing-n:127.0.0.1:39720_dbg%2Fh) [n:127.0.0.1:39720_dbg%2Fh ] 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> 18652 INFO (zkCallback-26-thread-1-processing-n:127.0.0.1:59237_dbg%2Fh) [n:127.0.0.1:59237_dbg%2Fh ] 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> 18655 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:39720_dbg%2Fh ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 18656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[B9416C87DB895D6E]) [n:127.0.0.1:39720 [...truncated too long message...] BeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 3233118 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[B9416C87DB895D6E]-worker) [n:127.0.0.1:57650_a_%2Fg c:addReplicaColl s:shard1 r:core_node7 x:propertyDotName] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Aug 30, 2015 7:00:09 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l_dv=BlockTreeOrds(blocksize=128), _version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=PostingsFormat(name=Direct), a_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=BlockTreeOrds(blocksize=128), range_facet_i_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text=FST50, range_facet_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), timestamp=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=en_AU, timezone=America/Louisville [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=2,free=232750424,total=535822336 [junit4] 2> NOTE: All tests run in this JVM: [FullSolrCloudDistribCmdsTest, TestDefaultSimilarityFactory, CollectionReloadTest, RequestHandlersTest, HdfsWriteToMultipleCollectionsTest, TestClassNameShortening, BadComponentTest, HdfsThreadLeakTest, TestConfigReload, TestXIncludeConfig, TestConfigSetProperties, DistributedDebugComponentTest, TestCryptoKeys, TestScoreJoinQPNoScore, OverseerTaskQueueTest, TestMacros, TestManagedSchemaDynamicFieldResource, TestImplicitCoreProperties, TestOrdValues, RecoveryAfterSoftCommitTest, CurrencyFieldXmlFileTest, TestShardHandlerFactory, TestCollectionAPI, TestTrieFacet, TestManagedStopFilterFactory, TestSolrCloudWithKerberosAlt, TestSolrDynamicMBean, OverseerTest, RAMDirectoryFactoryTest, CloudExitableDirectoryReaderTest, TestGroupingSearch, BigEndianAscendingWordDeserializerTest, TestFastLRUCache, TestBulkSchemaConcurrent, TestMinMaxOnMultiValuedField, ParsingFieldUpdateProcessorsTest, TestAuthorizationFramework, TestHighFrequencyDictionaryFactory, FieldAnalysisRequestHandlerTest, SecurityConfHandlerTest, TestLRUCache, CloudMLTQParserTest, OverriddenZkACLAndCredentialsProvidersTest, TestStressRecovery, LeaderElectionTest, QueryParsingTest, CdcrUpdateLogTest, TestIndexSearcher, TestLRUStatsCache, LukeRequestHandlerTest, MigrateRouteKeyTest, TestFastWriter, TestSolr4Spatial2, TestAddFieldRealTimeGet, BasicAuthIntegrationTest, TestSearcherReuse, ZkNodePropsTest, TestCoreDiscovery, SimpleMLTQParserTest, TestManagedSchemaFieldTypeResource, SuggesterWFSTTest, TestBulkSchemaAPI, ResponseHeaderTest, TestDocBasedVersionConstraints, TestManagedSynonymFilterFactory, TestHashQParserPlugin, NoCacheHeaderTest, TestExtendedDismaxParser, HdfsDirectoryTest, DeleteReplicaTest, PluginInfoTest, SearchHandlerTest, SharedFSAutoReplicaFailoverTest, SystemInfoHandlerTest, HdfsBasicDistributedZkTest, SparseHLLTest, MultiThreadedOCPTest, ClusterStateTest, TestCharFilters, TestSmileRequest, StatelessScriptUpdateProcessorFactoryTest, TestManagedResource, TestSolrCLIRunExample, HighlighterConfigTest, TestDefaultSearchFieldResource, RegexBoostProcessorTest, TestUniqueKeyFieldResource, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [349/543] on J2 in 1274.55s, 1 test, 1 error <<< FAILURES! [...truncated 630 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: 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:514: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 543 suites, 2145 tests, 2 errors, 29 ignored (17 assumptions) Total time: 190 minutes 24 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 Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
