Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/736/
3 tests failed. REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=5613, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=5613, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33120/_g/b: Could not find collection : awholynewstresscollection_collection0_0 at __randomizedtesting.SeedInfo.seed([BFEA587A71B28EC1]: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:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) REGRESSION: org.apache.solr.cloud.hdfs.StressHdfsTest.test Error Message: Error from server at http://127.0.0.1:44959: Could not find collection : delete_data_dir Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:44959: Could not find collection : delete_data_dir at __randomizedtesting.SeedInfo.seed([BFEA587A71B28EC1:37BE67A0DF4EE339]: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:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.hdfs.StressHdfsTest.createAndDeleteCollection(StressHdfsTest.java:204) at org.apache.solr.cloud.hdfs.StressHdfsTest.test(StressHdfsTest.java:98) 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:483) 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:960) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) 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) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=48768, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=48768, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:35668: Could not find collection : awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([BFEA587A71B28EC1]: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:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) Build Log: [...truncated 10175 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.StressHdfsTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/init-core-data-001 [junit4] 2> 192860 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 192974 WARN (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 193010 WARN (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 193021 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 193071 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_50456_hdfs____9cmfpg/webapp [junit4] 2> 193322 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 193885 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:50456 [junit4] 2> 194147 WARN (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 194149 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 194170 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_57190_datanode____.2v9beb/webapp [junit4] 2> 194381 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 195041 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:57190 [junit4] 2> 195110 WARN (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 195113 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 195159 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_55258_datanode____xkq4v0/webapp [junit4] 2> 195376 INFO (IPC Server handler 2 on 53676) [ ] BlockStateChange BLOCK* processReport: from storage DS-b6cf5356-6be7-4dca-a97d-c443b162d983 node DatanodeRegistration(127.0.0.1, datanodeUuid=7d9c57cd-6d94-489b-8e4a-ce21e481e40c, infoPort=57190, ipcPort=51099, storageInfo=lv=-56;cid=testClusterID;nsid=609059039;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs [junit4] 2> 195377 INFO (IPC Server handler 2 on 53676) [ ] BlockStateChange BLOCK* processReport: from storage DS-66983b53-a017-4c2a-ae69-6b9e6f2f0bc5 node DatanodeRegistration(127.0.0.1, datanodeUuid=7d9c57cd-6d94-489b-8e4a-ce21e481e40c, infoPort=57190, ipcPort=51099, storageInfo=lv=-56;cid=testClusterID;nsid=609059039;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 195392 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 196049 INFO (SUITE-StressHdfsTest-seed#[BFEA587A71B28EC1]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:55258 [junit4] 2> 196229 INFO (IPC Server handler 3 on 53676) [ ] BlockStateChange BLOCK* processReport: from storage DS-5cac1766-cd52-42c2-9aea-394027ad3bde node DatanodeRegistration(127.0.0.1, datanodeUuid=511acd6c-0a9c-4e7f-a093-64658d14c43f, infoPort=55258, ipcPort=33655, storageInfo=lv=-56;cid=testClusterID;nsid=609059039;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs [junit4] 2> 196230 INFO (IPC Server handler 3 on 53676) [ ] BlockStateChange BLOCK* processReport: from storage DS-531e92bb-6e7e-4fa2-b8aa-264aa31d2725 node DatanodeRegistration(127.0.0.1, datanodeUuid=511acd6c-0a9c-4e7f-a093-64658d14c43f, infoPort=55258, ipcPort=33655, storageInfo=lv=-56;cid=testClusterID;nsid=609059039;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 196336 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 196336 INFO (Thread-435) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 196336 INFO (Thread-435) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 196436 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.ZkTestServer start zk server on port:49245 [junit4] 2> 196437 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 196459 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 196472 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1731274e name:ZooKeeperConnection Watcher:127.0.0.1:49245 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 196472 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 196473 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 196473 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 196481 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 196489 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 196508 INFO (zkCallback-38-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c0b9476 name:ZooKeeperConnection Watcher:127.0.0.1:49245/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 196508 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 196509 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 196509 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 196525 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 196532 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 196534 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 196536 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196537 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 196539 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196540 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 196542 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196542 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 196544 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196544 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 196546 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196546 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 196547 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196548 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 196549 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196549 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 196551 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196551 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 196553 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196553 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 196555 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196555 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 196557 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 196557 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 197018 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores/collection1 [junit4] 2> 197020 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 197031 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6de5a21b{/,null,AVAILABLE} [junit4] 2> 197032 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4f5dddd2{HTTP/1.1}{127.0.0.1:52482} [junit4] 2> 197032 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.e.j.s.Server Started @200273ms [junit4] 2> 197032 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:53676/hdfs__localhost_53676__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001_tempDir-002_control_data, hostContext=/, hostPort=52482, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores} [junit4] 2> 197033 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 197033 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/' [junit4] 2> 197051 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/solr.xml [junit4] 2> 197070 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores [junit4] 2> 197071 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.CoreContainer New CoreContainer 503712654 [junit4] 2> 197071 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/] [junit4] 2> 197071 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/lib [junit4] 2> 197074 WARN (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/lib). [junit4] 2> 197098 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 197103 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 197104 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 197104 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 197104 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49245/solr [junit4] 2> 197104 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 197104 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 197136 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 197147 INFO (zkCallback-40-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2f1242dc name:ZooKeeperConnection Watcher:127.0.0.1:49245 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 197147 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 197148 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 197176 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 197201 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@32d09f7e name:ZooKeeperConnection Watcher:127.0.0.1:49245/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 197202 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 197203 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 197212 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 197214 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 197217 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 197219 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 197221 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 197223 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 197224 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 197225 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52482_ [junit4] 2> 197226 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:52482_ [junit4] 2> 197238 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 197239 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 197241 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 197241 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94148556074188803-127.0.0.1:52482_-n_0000000000 [junit4] 2> 197242 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52482_ [junit4] 2> 197242 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 197243 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.Overseer Overseer (id=94148556074188803-127.0.0.1:52482_-n_0000000000) starting [junit4] 2> 197245 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 197259 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 197265 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 197269 INFO (OverseerCollectionProcessor-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 197278 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 197281 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 197281 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 197282 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores [junit4] 2> 197283 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 197283 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores/collection1/ [junit4] 2> 197283 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 197291 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 197291 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 197305 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 197305 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 197306 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 197306 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:52482_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 197308 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52482", [junit4] 2> "node_name":"127.0.0.1:52482_", [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> 197309 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52482", [junit4] 2> "node_name":"127.0.0.1:52482_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 197309 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 197310 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 197314 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] 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> 197316 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 198308 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 198308 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 198310 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 198310 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 198312 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 198312 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores/collection1/' [junit4] 2> 198336 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 198360 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 198376 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 198398 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 198399 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 198404 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 198527 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 198533 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 198537 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 198562 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 198567 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 198571 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 198573 WARN (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 198574 WARN (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 198574 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 198575 WARN (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 198575 WARN (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 198575 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 198575 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 198586 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:53676/solr_hdfs_home [junit4] 2> 198586 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 198587 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 198587 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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@21713abe [junit4] 2> 198587 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:53676/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 198634 WARN (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 203638 WARN (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 208658 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:53676/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 208659 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:53676/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 208660 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:53676/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 208708 WARN (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:53676/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 208708 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:53676/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 208732 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 208733 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [8388608] will allocate [1] slabs and use ~[8388608] bytes [junit4] 2> 208733 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Creating new global HDFS BlockCache [junit4] 2> 210572 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 210572 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:53676/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 210572 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:53676/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 210573 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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=18, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=32.4111328125, floorSegmentMB=0.4814453125, forceMergeDeletesPctAllowed=16.39665767607123, segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2503651975721729 [junit4] 2> 210636 INFO (IPC Server handler 9 on 53676) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:50350 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5cac1766-cd52-42c2-9aea-394027ad3bde:NORMAL:127.0.0.1:60114|RBW], ReplicaUnderConstruction[[DISK]DS-b6cf5356-6be7-4dca-a97d-c443b162d983:NORMAL:127.0.0.1:50350|FINALIZED]]} size 0 [junit4] 2> 210646 INFO (IPC Server handler 5 on 53676) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60114 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5cac1766-cd52-42c2-9aea-394027ad3bde:NORMAL:127.0.0.1:60114|RBW], ReplicaUnderConstruction[[DISK]DS-b6cf5356-6be7-4dca-a97d-c443b162d983:NORMAL:127.0.0.1:50350|FINALIZED]]} size 0 [junit4] 2> 210665 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(BlockDirectory(HdfsDirectory@b8659af2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@618230bb); maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 210666 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 210686 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 210687 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 210687 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 210687 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 210687 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 210687 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210688 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210688 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210688 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210689 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:52482_ 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 hdfs://localhost:53676/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 210690 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 210691 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 210692 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 210693 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 210696 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210697 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210703 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog [junit4] 2> 210703 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing HdfsUpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 tlogDfsReplication=2 [junit4] 2> 210730 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 210730 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 210734 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@578cb0b4 [junit4] 2> 210797 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(BlockDirectory(HdfsDirectory@b8659af2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@618230bb); maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 210797 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 210797 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@111ae5b9[collection1] main [junit4] 2> 210798 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210799 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210799 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210800 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 210800 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 210800 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210800 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210801 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210801 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 210801 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 210801 INFO (searcherExecutor-232-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@111ae5b9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 210815 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210816 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 256 version buckets from index [junit4] 2> 210816 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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> 210816 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ 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 1506376912367255552 [junit4] 2> 210816 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 0 ms to seed version buckets with highest version 1506376912367255552 [junit4] 2> 210816 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 210817 INFO (coreLoadExecutor-231-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 210817 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:52482 collection:control_collection shard:shard1 [junit4] 2> 210818 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ 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> 210823 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ 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/94148556074188803-core_node1-n_0000000000 [junit4] 2> 210824 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 210825 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 210826 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] 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> 210827 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 210827 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ 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> 210828 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] 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> 210829 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:52482/collection1/ [junit4] 2> 210830 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 210830 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:52482/collection1/ has no replicas [junit4] 2> 210830 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:52482/collection1/ shard1 [junit4] 2> 210830 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 210832 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 210836 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 210839 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:52482", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 210943 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] 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> 210945 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 210993 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:52482/collection1/ and leader is http://127.0.0.1:52482/collection1/ [junit4] 2> 210993 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52482 [junit4] 2> 210993 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 210993 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 210993 INFO (coreZkRegister-225-thread-1-processing-n:127.0.0.1:52482_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:52482_ 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> 210995 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 210996 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52482", [junit4] 2> "node_name":"127.0.0.1:52482_", [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> 210996 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52482", [junit4] 2> "node_name":"127.0.0.1:52482_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 211100 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] 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> 211101 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 211312 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 211312 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 211314 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78399a3d name:ZooKeeperConnection Watcher:127.0.0.1:49245/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 211314 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 211315 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 211315 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 211318 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 211736 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/cores/collection1 [junit4] 2> 211738 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001 [junit4] 2> 211739 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 211794 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e11f5ae{/,null,AVAILABLE} [junit4] 2> 211795 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6d1f42b5{HTTP/1.1}{127.0.0.1:58721} [junit4] 2> 211795 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.e.j.s.Server Started @215036ms [junit4] 2> 211795 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:53676/hdfs__localhost_53676__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=58721, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/cores} [junit4] 2> 211796 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 211796 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/' [junit4] 2> 211820 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/solr.xml [junit4] 2> 211825 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/cores [junit4] 2> 211834 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.CoreContainer New CoreContainer 2039297881 [junit4] 2> 211834 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/] [junit4] 2> 211834 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/lib [junit4] 2> 211835 WARN (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/lib). [junit4] 2> 211852 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] 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> 211858 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 211858 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 211858 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 211859 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49245/solr [junit4] 2> 211859 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 211859 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 211889 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 211905 INFO (zkCallback-45-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3cd4d8fe name:ZooKeeperConnection Watcher:127.0.0.1:49245 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 211906 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 211907 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 211910 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 211946 INFO (zkCallback-46-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b956baa name:ZooKeeperConnection Watcher:127.0.0.1:49245/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 211947 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 211960 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 212968 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58721_ [junit4] 2> 212969 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58721_ [junit4] 2> 212975 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 212976 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94148556074188806-127.0.0.1:58721_-n_0000000001 [junit4] 2> 212977 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94148556074188803-127.0.0.1:52482_-n_0000000000 to know if I could be the leader [junit4] 2> 212977 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 212978 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 212978 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/cores [junit4] 2> 212979 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 212979 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/cores/collection1/ [junit4] 2> 212980 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 212980 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 212980 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 212981 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 212981 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 212986 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58721", [junit4] 2> "node_name":"127.0.0.1:58721_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 212986 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58721", [junit4] 2> "node_name":"127.0.0.1:58721_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 212986 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 212987 INFO (OverseerStateUpdate-94148556074188803-127.0.0.1:52482_-n_0000000000) [n:127.0.0.1:52482_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 212988 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 212988 INFO (zkCallback-46-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 212988 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 212991 INFO (zkCallback-46-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 212991 INFO (zkCallback-41-thread-1-processing-n:127.0.0.1:52482_) [n:127.0.0.1:52482_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 212991 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 213009 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 213010 INFO (TEST-StressHdfsTest.test-seed#[BFEA587A71B28EC1]) [n:127.0.0.1:58721_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 213982 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 213982 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 213983 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 213983 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 213984 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 213984 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.StressHdfsTest_BFEA587A71B28EC1-001/shard-1-001/cores/collection1/' [junit4] 2> 213997 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 214002 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 214006 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 214029 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 214031 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 214039 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 214154 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 214162 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 214163 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 214174 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 214184 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 214190 INFO (coreLoadExecutor-242-thread-1-processing-n:127.0.0.1:58721_) [n:127.0.0.1:58721_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading [...truncated too long message...] p.metrics2.lib.MethodMetric$2.snapshot(MethodMetric.java:111) [junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric.snapshot(MethodMetric.java:144) [junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsRegistry.snapshot(MetricsRegistry.java:387) [junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:195) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.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:483) [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> 2991239 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[BFEA587A71B28EC1]-worker) [n:127.0.0.1:59625_ c:addReplicaColl s:shard1 r:core_node7 x:propertyDotName] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Jul 11, 2015 2:22:33 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=BlockTreeOrds(blocksize=128), range_facet_l_dv=PostingsFormat(name=Memory doPackFST= true), _version_=BlockTreeOrds(blocksize=128), multiDefault=PostingsFormat(name=Asserting), a_t=BlockTreeOrds(blocksize=128), intDefault=BlockTreeOrds(blocksize=128), a_i1=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=Memory doPackFST= true), range_facet_i_dv=BlockTreeOrds(blocksize=128), text=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_l=BlockTreeOrds(blocksize=128), timestamp=BlockTreeOrds(blocksize=128)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=zh_CN, timezone=Africa/Gaborone [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=4,threads=2,free=164315896,total=531103744 [junit4] 2> NOTE: All tests run in this JVM: [DirectUpdateHandlerTest, SimpleFacetsTest, PrimUtilsTest, ActionThrottleTest, TestBM25SimilarityFactory, TestExtendedDismaxParser, HardAutoCommitTest, CloudExitableDirectoryReaderTest, DirectUpdateHandlerOptimizeTest, SearchHandlerTest, TestPerFieldSimilarity, HdfsDirectoryTest, SynonymTokenizerTest, TestStressRecovery, TestCopyFieldCollectionResource, TestManagedResourceStorage, QueryParsingTest, TestSystemIdResolver, SolrInfoMBeanTest, StressHdfsTest, AtomicUpdatesTest, TestSolrConfigHandlerConcurrent, TestSolr4Spatial2, CollectionReloadTest, TestFieldTypeCollectionResource, VersionInfoTest, DistribJoinFromCollectionTest, TestMiniSolrCloudClusterSSL, TestHdfsUpdateLog, TestFieldCollectionResource, TestSearcherReuse, MoreLikeThisHandlerTest, SpellCheckCollatorTest, TestSolrJ, TestXIncludeConfig, NumericFieldsTest, TestHashQParserPlugin, RecoveryAfterSoftCommitTest, FullSolrCloudDistribCmdsTest, TestUniqueKeyFieldResource, TestObjectReleaseTracker, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [344/514] on J2 in 2221.17s, 1 test, 1 error <<< FAILURES! [...truncated 561 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:513: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: There were test failures: 514 suites, 2022 tests, 3 errors, 20 ignored (10 assumptions) Total time: 174 minutes 37 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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org