Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/5/
2 tests failed. FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: GC overhead limit exceeded Stack Trace: java.lang.OutOfMemoryError: GC overhead limit exceeded at __randomizedtesting.SeedInfo.seed([667D296926F4C3DA:EE2916B38808AE22]:0) at java.util.HashMap.entrySet0(HashMap.java:1073) at java.util.HashMap.entrySet(HashMap.java:1068) at java.util.AbstractMap.hashCode(AbstractMap.java:492) at java.util.HashMap.hash(HashMap.java:362) at java.util.HashMap.put(HashMap.java:492) at java.util.HashSet.add(HashSet.java:217) at org.apache.solr.cloud.CloudInspectUtil.showDiff(CloudInspectUtil.java:125) at org.apache.solr.cloud.CloudInspectUtil.compareResults(CloudInspectUtil.java:206) at org.apache.solr.cloud.CloudInspectUtil.compareResults(CloudInspectUtil.java:167) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:677) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:153) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=15287, name=collection0, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=15287, name=collection0, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:42995: Could not find collection : awholynewstresscollection_collection0_0 at __randomizedtesting.SeedInfo.seed([667D296926F4C3DA]: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:1098) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805) 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 10622 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/init-core-data-001 [junit4] 2> 701312 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 701313 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 701361 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 701374 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 701378 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 701396 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-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_59906_hdfs____.wqvzaz/webapp [junit4] 2> 701523 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 701772 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:59906 [junit4] 2> 701854 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 701856 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 701873 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-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_51543_datanode____wpfein/webapp [junit4] 2> 701997 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 702231 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51543 [junit4] 2> 702308 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 702310 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 702329 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-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_58700_datanode____fraaj/webapp [junit4] 2> 702375 INFO (IPC Server handler 3 on 40597) [ ] BlockStateChange BLOCK* processReport: from storage DS-bbf7e801-f104-410b-ad19-802438a467bf node DatanodeRegistration(127.0.0.1, datanodeUuid=9328feef-42b3-4438-93d5-8f6a6b47a873, infoPort=51543, ipcPort=43490, storageInfo=lv=-56;cid=testClusterID;nsid=887295706;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs [junit4] 2> 702375 INFO (IPC Server handler 3 on 40597) [ ] BlockStateChange BLOCK* processReport: from storage DS-283cbc1e-c2ec-4e1b-9ff6-6059940e31d9 node DatanodeRegistration(127.0.0.1, datanodeUuid=9328feef-42b3-4438-93d5-8f6a6b47a873, infoPort=51543, ipcPort=43490, storageInfo=lv=-56;cid=testClusterID;nsid=887295706;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 702488 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 702685 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58700 [junit4] 2> 702766 INFO (IPC Server handler 8 on 40597) [ ] BlockStateChange BLOCK* processReport: from storage DS-de7571cc-db3f-4a85-8d82-baf368b2b2c9 node DatanodeRegistration(127.0.0.1, datanodeUuid=9cecd7ad-a81c-4b52-ac9c-4fc217160cab, infoPort=58700, ipcPort=51060, storageInfo=lv=-56;cid=testClusterID;nsid=887295706;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 702767 INFO (IPC Server handler 8 on 40597) [ ] BlockStateChange BLOCK* processReport: from storage DS-6c4f0860-7e86-434b-8e56-7c95c03ca83d node DatanodeRegistration(127.0.0.1, datanodeUuid=9cecd7ad-a81c-4b52-ac9c-4fc217160cab, infoPort=58700, ipcPort=51060, storageInfo=lv=-56;cid=testClusterID;nsid=887295706;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 702857 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 702858 INFO (Thread-10102) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 702858 INFO (Thread-10102) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 702958 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.ZkTestServer start zk server on port:52278 [junit4] 2> 702958 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 702958 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 702961 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@264e45a7 name:ZooKeeperConnection Watcher:127.0.0.1:52278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 702962 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 702962 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 702962 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 702965 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 702967 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 702975 INFO (zkCallback-679-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e9c70f9 name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 702976 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 702976 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 702976 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 702978 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 702981 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 702982 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 702985 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 702986 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 702988 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 702989 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 702990 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 702991 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 702992 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 702993 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 702994 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 702994 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 702996 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 702997 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 702999 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 702999 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 703001 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 703001 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 703005 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 703005 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 703007 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 703007 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 703010 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 703010 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 703013 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 703013 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 703015 INFO (zkCallback-680-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2e187782 name:ZooKeeperConnection Watcher:127.0.0.1:52278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 703015 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 703015 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 703016 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 703017 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 703018 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 703022 INFO (zkCallback-681-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@52504684 name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 703022 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 703022 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 703023 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 703023 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 703028 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 703028 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 703030 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 703030 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 703033 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 703033 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 703036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 703036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 703040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 703041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 703044 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 703045 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 703046 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 703047 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 703049 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 703049 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 703051 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 703052 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 703056 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 703057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 703059 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 703059 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 703162 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores/collection1 [junit4] 2> 703164 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 703167 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@523be601{/,null,AVAILABLE} [junit4] 2> 703168 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5a7975d4{HTTP/1.1}{127.0.0.1:39347} [junit4] 2> 703168 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.e.j.s.Server Started @706876ms [junit4] 2> 703168 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:40597/hdfs__localhost_40597__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001_tempDir-002_control_data, hostPort=39347, hostContext=/, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores} [junit4] 2> 703169 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 703169 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/' [junit4] 2> 703193 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 703196 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 703197 INFO (zkCallback-682-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@559a78ee name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 703197 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 703197 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 703199 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 703199 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/solr.xml [junit4] 2> 703214 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores [junit4] 2> 703214 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.CoreContainer New CoreContainer 1012968287 [junit4] 2> 703214 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/] [junit4] 2> 703214 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/lib [junit4] 2> 703214 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/lib). [junit4] 2> 703223 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] 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> 703225 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 703225 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 703225 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 703226 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52278/solr [junit4] 2> 703226 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 703226 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 703226 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 703230 INFO (zkCallback-684-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@555ee8aa name:ZooKeeperConnection Watcher:127.0.0.1:52278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 703230 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 703231 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 703234 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 703235 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@617e6614 name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 703235 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 703236 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 703239 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 703242 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 703244 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 703248 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 703254 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 703256 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 703257 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 703259 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 703260 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39347_ [junit4] 2> 703261 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:39347_ [junit4] 2> 703262 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 703263 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 703267 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 703268 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95160657043324934-127.0.0.1:39347_-n_0000000000 [junit4] 2> 703270 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39347_ [junit4] 2> 703271 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 703273 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.Overseer Overseer (id=95160657043324934-127.0.0.1:39347_-n_0000000000) starting [junit4] 2> 703275 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 703287 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 703289 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 703289 INFO (OverseerCollectionProcessor-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 703292 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 703310 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 703311 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 703311 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores [junit4] 2> 703312 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.SolrCore Created CoreDescriptor: {absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores/collection1/, schema=schema.xml, collection=control_collection, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores/collection1, shard=, coreNodeName=, dataDir=data/, transient=false, config=solrconfig.xml, name=collection1, loadOnStartup=true, configSetProperties=configsetprops.json} [junit4] 2> 703312 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores/collection1/ [junit4] 2> 703313 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 703314 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 703314 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 703314 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2 [junit4] 2> 703314 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [n:127.0.0.1:39347_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 703314 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 703315 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 703316 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "base_url":"http://127.0.0.1:39347", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "core":"collection1", [junit4] 2> "node_name":"127.0.0.1:39347_", [junit4] 2> "numShards":"1", [junit4] 2> "shard":null, [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 703316 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "base_url":"http://127.0.0.1:39347", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "core":"collection1", [junit4] 2> "node_name":"127.0.0.1:39347_", [junit4] 2> "numShards":"1", [junit4] 2> "shard":null, [junit4] 2> "operation":"state"} [junit4] 2> 703317 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 703317 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 703318 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] 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> 703320 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 704316 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 704316 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 704317 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 704317 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 704319 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 704319 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores/collection1/' [junit4] 2> 704337 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 704343 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 704356 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2 [junit4] 2> 704386 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 704387 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 704396 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 704682 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 704691 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 704693 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 704708 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 704712 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 704715 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 704716 WARN (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 704716 WARN (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 704717 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 704718 WARN (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 704718 WARN (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 704719 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2 [junit4] 2> 704720 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 704720 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 704720 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:40597/solr_hdfs_home [junit4] 2> 704720 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 704720 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 704721 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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@6c045f01 [junit4] 2> 704721 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 704738 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 704739 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 704739 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 704772 WARN (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 704772 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 704786 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 704786 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 704787 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10331445500101646] [junit4] 2> 704814 INFO (IPC Server handler 9 on 40597) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:35726 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-de7571cc-db3f-4a85-8d82-baf368b2b2c9:NORMAL:127.0.0.1:58734|RBW], ReplicaUnderConstruction[[DISK]DS-283cbc1e-c2ec-4e1b-9ff6-6059940e31d9:NORMAL:127.0.0.1:35726|FINALIZED]]} size 0 [junit4] 2> 704816 INFO (IPC Server handler 0 on 40597) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58734 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-de7571cc-db3f-4a85-8d82-baf368b2b2c9:NORMAL:127.0.0.1:58734|RBW], ReplicaUnderConstruction[[DISK]DS-283cbc1e-c2ec-4e1b-9ff6-6059940e31d9:NORMAL:127.0.0.1:35726|FINALIZED]]} size 0 [junit4] 2> 704820 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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(HdfsDirectory@b6e0a4d7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e7612c4; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 704820 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 704822 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:39347_ 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:40597/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 704828 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 704829 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 704829 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 704829 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 704829 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 704829 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704830 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704830 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704830 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704834 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 704835 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 704837 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 704838 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 704843 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /schema,/update/json,/admin/segments,/admin/system,/admin/properties,/get,/replication,/admin/file,/update/json/docs,standard,/admin/luke,/admin/ping,/admin/mbeans,/update/csv,/update,/admin/logging,/config,/admin/plugins,/admin/threads [junit4] 2> 704845 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704846 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704846 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704861 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 704861 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 704863 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1369075563, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 704875 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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(HdfsDirectory@b6e0a4d7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e7612c4; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 704875 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 704875 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4710f52b[collection1] main [junit4] 2> 704876 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704876 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704877 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704877 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 704877 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 704877 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704878 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704878 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704878 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 704878 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 704879 INFO (searcherExecutor-2520-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4710f52b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 704882 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704882 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704882 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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> 704882 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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 1522570514814468096 [junit4] 2> 704882 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ 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 1522570514814468096 [junit4] 2> 704882 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 704883 INFO (coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 704884 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 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:39347 collection:control_collection shard:shard1 [junit4] 2> 704888 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 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> 704892 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 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/95160657043324934-core_node1-n_0000000000 [junit4] 2> 704893 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 704895 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 704895 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] 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> 704896 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 704896 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 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> 704896 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:39347/collection1/ [junit4] 2> 704897 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] 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> 704897 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 704897 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:39347/collection1/ has no replicas [junit4] 2> 704898 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 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:39347/collection1/ shard1 [junit4] 2> 704898 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 704899 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 704907 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 704908 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] 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:39347", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 705012 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] 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> 705014 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 705059 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:39347/collection1/ and leader is http://127.0.0.1:39347/collection1/ [junit4] 2> 705059 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 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:39347 [junit4] 2> 705059 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 705059 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 705059 INFO (coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 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> 705061 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 705062 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "base_url":"http://127.0.0.1:39347", [junit4] 2> "roles":null, [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "core":"collection1", [junit4] 2> "node_name":"127.0.0.1:39347_", [junit4] 2> "numShards":"1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 705062 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "base_url":"http://127.0.0.1:39347", [junit4] 2> "roles":null, [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "core":"collection1", [junit4] 2> "node_name":"127.0.0.1:39347_", [junit4] 2> "numShards":"1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard1", [junit4] 2> "operation":"state"} [junit4] 2> 705165 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] 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> 705169 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 705318 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 705318 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 705320 INFO (zkCallback-687-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d1e7e11 name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 705321 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 705321 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 705321 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 705327 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 705327 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 705327 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 705328 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 705331 INFO (zkCallback-688-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@696e2c92 name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 705331 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 705331 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 705335 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 705336 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 4 [junit4] 2> 705337 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 705442 INFO (OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) [n:127.0.0.1:39347_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 705442 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/cores/collection1 [junit4] 2> 705443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001 [junit4] 2> 705443 INFO (zkCallback-687-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 705443 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] 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> 705444 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 705447 INFO (zkCallback-687-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 705447 INFO (zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 705449 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6cc60395{/,null,AVAILABLE} [junit4] 2> 705450 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2015eedc{HTTP/1.1}{127.0.0.1:52230} [junit4] 2> 705450 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.e.j.s.Server Started @709158ms [junit4] 2> 705450 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, solr.data.dir=hdfs://localhost:40597/hdfs__localhost_40597__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001_tempDir-002_jetty1, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/cores, hostContext=/, hostPort=52230} [junit4] 2> 705450 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 705450 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/' [junit4] 2> 705470 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 705473 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 705474 INFO (zkCallback-689-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10a6fae name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 705474 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 705474 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 705476 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 705476 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/solr.xml [junit4] 2> 705499 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/cores [junit4] 2> 705499 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.CoreContainer New CoreContainer 1064714485 [junit4] 2> 705499 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/] [junit4] 2> 705499 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/lib [junit4] 2> 705499 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/lib). [junit4] 2> 705514 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] 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> 705515 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 705515 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 705516 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 705516 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52278/solr [junit4] 2> 705516 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 705516 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 705517 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 705518 INFO (zkCallback-691-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11d0104e name:ZooKeeperConnection Watcher:127.0.0.1:52278 got event WatchedEvent state [...truncated too long message...] ockTreeOrds(blocksize=128), 1parent_f2_s=Lucene50(blocksize=128), 16parent_f2_s=Lucene50(blocksize=128), 42parent_f1_s=PostingsFormat(name=Direct), 12parent_f2_s=Lucene50(blocksize=128), 4grand1_s=PostingsFormat(name=Asserting), 9grand6_s=BlockTreeOrds(blocksize=128), 38parent_f2_s=Lucene50(blocksize=128), 2parent_f1_s=PostingsFormat(name=Direct), 43parent_f1_s=PostingsFormat(name=Asserting), 17parent_f2_s=PostingsFormat(name=Asserting), 50parent_f1_s=PostingsFormat(name=Asserting), 6grand2_s=Lucene50(blocksize=128), 22parent_f2_s=PostingsFormat(name=Asserting), 9grand0_s=PostingsFormat(name=Asserting), 45parent_f2_s=Lucene50(blocksize=128), 3grand0_s=PostingsFormat(name=Asserting), 4child0_s=Lucene50(blocksize=128), 5child4_s=PostingsFormat(name=Asserting), 1parent_f1_s=PostingsFormat(name=Asserting), 5grand3_s=Lucene50(blocksize=128), 49parent_f2_s=Lucene50(blocksize=128), 9parent_f1_s=PostingsFormat(name=Asserting), 3child1_s=Lucene50(blocksize=128), 6parent_f2_s=PostingsFormat(name=Asserting), 2parent_f2_s=PostingsFormat(name=Asserting), 35parent_f1_s=PostingsFormat(name=Direct), 8grand0_s=Lucene50(blocksize=128), 5parent_f1_s=PostingsFormat(name=Asserting), 3parent_f2_s=PostingsFormat(name=Direct), 40parent_f1_s=Lucene50(blocksize=128), 15parent_f1_s=Lucene50(blocksize=128), 7grand3_s=PostingsFormat(name=Direct), 32parent_f2_s=PostingsFormat(name=Direct), 42parent_f2_s=BlockTreeOrds(blocksize=128), 48parent_f2_s=PostingsFormat(name=Asserting), 7parent_f2_s=PostingsFormat(name=Direct), 21parent_f1_s=PostingsFormat(name=Asserting), 6grand3_s=PostingsFormat(name=Asserting), 5child3_s=Lucene50(blocksize=128), rnd_b=BlockTreeOrds(blocksize=128), 49parent_f1_s=BlockTreeOrds(blocksize=128), 23parent_f1_s=BlockTreeOrds(blocksize=128), 20parent_f1_s=PostingsFormat(name=Direct), 7grand4_s=PostingsFormat(name=Asserting), 0parent_f1_s=Lucene50(blocksize=128), a_t=PostingsFormat(name=Direct), 29parent_f1_s=BlockTreeOrds(blocksize=128), 31parent_f1_s=PostingsFormat(name=Direct), 40parent_f2_s=PostingsFormat(name=Asserting), 39parent_f1_s=PostingsFormat(name=Direct), 44parent_f1_s=Lucene50(blocksize=128), 1child0_s=BlockTreeOrds(blocksize=128), 9grand8_s=PostingsFormat(name=Asserting), 7grand5_s=Lucene50(blocksize=128), 5child0_s=PostingsFormat(name=Asserting), 5grand4_s=BlockTreeOrds(blocksize=128), 21parent_f2_s=PostingsFormat(name=Direct), 2grand1_s=PostingsFormat(name=Asserting), 8grand5_s=PostingsFormat(name=Asserting), 7grand2_s=BlockTreeOrds(blocksize=128), 46parent_f1_s=PostingsFormat(name=Direct), 24parent_f2_s=BlockTreeOrds(blocksize=128), 8grand1_s=PostingsFormat(name=Asserting), 18parent_f2_s=PostingsFormat(name=Direct), 3grand2_s=BlockTreeOrds(blocksize=128), 12parent_f1_s=BlockTreeOrds(blocksize=128), 11parent_f1_s=Lucene50(blocksize=128), 3grand1_s=Lucene50(blocksize=128), 45parent_f1_s=BlockTreeOrds(blocksize=128), 18parent_f1_s=BlockTreeOrds(blocksize=128), 46parent_f2_s=BlockTreeOrds(blocksize=128), 19parent_f2_s=BlockTreeOrds(blocksize=128), 2child1_s=BlockTreeOrds(blocksize=128), id=BlockTreeOrds(blocksize=128), 36parent_f1_s=PostingsFormat(name=Asserting), 25parent_f2_s=PostingsFormat(name=Direct), 44parent_f2_s=PostingsFormat(name=Asserting), 38parent_f1_s=BlockTreeOrds(blocksize=128), 6parent_f1_s=PostingsFormat(name=Direct), 43parent_f2_s=PostingsFormat(name=Direct), 14parent_f2_s=PostingsFormat(name=Direct), 22parent_f1_s=Lucene50(blocksize=128), 8grand3_s=BlockTreeOrds(blocksize=128), 4grand3_s=BlockTreeOrds(blocksize=128), 26parent_f2_s=PostingsFormat(name=Asserting), 31parent_f2_s=BlockTreeOrds(blocksize=128), 4parent_f1_s=Lucene50(blocksize=128), 17parent_f1_s=PostingsFormat(name=Direct), 5parent_f2_s=Lucene50(blocksize=128), _root_=PostingsFormat(name=Direct), 5grand2_s=PostingsFormat(name=Asserting), 14parent_f1_s=PostingsFormat(name=Asserting), 6grand5_s=BlockTreeOrds(blocksize=128), 33parent_f2_s=PostingsFormat(name=Asserting), 11parent_f2_s=PostingsFormat(name=Asserting), 27parent_f1_s=BlockTreeOrds(blocksize=128), 47parent_f2_s=PostingsFormat(name=Direct), 13parent_f2_s=BlockTreeOrds(blocksize=128), 9grand4_s=PostingsFormat(name=Asserting), 9grand2_s=BlockTreeOrds(blocksize=128), 9grand1_s=PostingsFormat(name=Direct), 8parent_f1_s=Lucene50(blocksize=128), 4child2_s=PostingsFormat(name=Direct), 9grand7_s=Lucene50(blocksize=128), 4grand0_s=Lucene50(blocksize=128), 30parent_f1_s=BlockTreeOrds(blocksize=128), 50parent_f2_s=PostingsFormat(name=Direct), 25parent_f1_s=PostingsFormat(name=Asserting), 8grand6_s=PostingsFormat(name=Direct), 10parent_f1_s=PostingsFormat(name=Asserting), 28parent_f2_s=PostingsFormat(name=Asserting), 9grand5_s=PostingsFormat(name=Direct), 26parent_f1_s=Lucene50(blocksize=128), 3child2_s=BlockTreeOrds(blocksize=128), 8grand7_s=BlockTreeOrds(blocksize=128), 47parent_f1_s=PostingsFormat(name=Asserting), 4child3_s=PostingsFormat(name=Asserting), 3parent_f1_s=BlockTreeOrds(blocksize=128), 37parent_f1_s=Lucene50(blocksize=128), 3child0_s=PostingsFormat(name=Asserting), 1grand0_s=PostingsFormat(name=Asserting), 7grand6_s=BlockTreeOrds(blocksize=128), 39parent_f2_s=BlockTreeOrds(blocksize=128), 27parent_f2_s=Lucene50(blocksize=128), 32parent_f1_s=PostingsFormat(name=Asserting), _version_=PostingsFormat(name=Direct), 19parent_f1_s=Lucene50(blocksize=128), 7grand1_s=Lucene50(blocksize=128), 6grand0_s=PostingsFormat(name=Direct), 13parent_f1_s=PostingsFormat(name=Direct), 5child2_s=BlockTreeOrds(blocksize=128), 4parent_f2_s=BlockTreeOrds(blocksize=128), 0parent_f2_s=BlockTreeOrds(blocksize=128), 9parent_f2_s=Lucene50(blocksize=128), 8grand2_s=PostingsFormat(name=Direct), 34parent_f2_s=Lucene50(blocksize=128), 5child1_s=PostingsFormat(name=Direct), 10parent_f2_s=PostingsFormat(name=Direct), 6grand4_s=PostingsFormat(name=Direct), text_t=PostingsFormat(name=Direct), 5grand1_s=PostingsFormat(name=Direct), 33parent_f1_s=Lucene50(blocksize=128), 41parent_f2_s=Lucene50(blocksize=128), 23parent_f2_s=Lucene50(blocksize=128), 8grand4_s=Lucene50(blocksize=128), 37parent_f2_s=PostingsFormat(name=Asserting), 2grand0_s=PostingsFormat(name=Direct), 9grand3_s=Lucene50(blocksize=128), 35parent_f2_s=BlockTreeOrds(blocksize=128), 34parent_f1_s=BlockTreeOrds(blocksize=128), 16parent_f1_s=PostingsFormat(name=Asserting), 8parent_f2_s=BlockTreeOrds(blocksize=128), 7grand0_s=PostingsFormat(name=Asserting)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=es_PE, timezone=Etc/GMT-6 [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=129101448,total=451411968 [junit4] 2> NOTE: All tests run in this JVM: [HdfsBasicDistributedZk2Test, DirectUpdateHandlerTest, MultiThreadedOCPTest, TestObjectReleaseTracker, TestSchemaManager, BadIndexSchemaTest, TestSolrCLIRunExample, SpellCheckCollatorTest, TestManagedSynonymFilterFactory, SegmentsInfoRequestHandlerTest, CleanupOldIndexTest, SearchHandlerTest, DistributedFacetPivotSmallAdvancedTest, DeleteReplicaTest, RankQueryTest, TestExtendedDismaxParser, TestCustomSort, TestLuceneMatchVersion, QueryEqualityTest, TestCollapseQParserPlugin, OverseerTest, TestBulkSchemaAPI, TestHighFrequencyDictionaryFactory, DistributedExpandComponentTest, ChangedSchemaMergeTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrConfigHandler, TestCollationFieldDocValues, TestQueryUtils, SimpleFacetsTest, TestExclusionRuleCollectionAccess, AddBlockUpdateTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, XmlUpdateRequestHandlerTest, SolrInfoMBeanTest, TestSchemaSimilarityResource, HardAutoCommitTest, TestAuthenticationFramework, TestReload, OverseerCollectionProcessorTest, BinaryUpdateRequestHandlerTest, TestPivotHelperCode, DocExpirationUpdateProcessorFactoryTest, PluginInfoTest, ConcurrentDeleteAndCreateCollectionTest, TestHdfsUpdateLog, MoreLikeThisHandlerTest, TestTolerantSearch, LeaderElectionTest, SampleTest, SynonymTokenizerTest, TestPerFieldSimilarity, DOMUtilTest, HdfsCollectionsAPIDistributedZkTest, FullSolrCloudDistribCmdsTest] [junit4] Completed [159/533] on J2 in 201.49s, 1 test, 1 error <<< FAILURES! [...truncated 1142 lines...] [junit4] JVM J2: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160105_231446_195.sysout [junit4] >>> JVM J2: stdout (verbatim) ---- [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/heapdumps/java_pid10560.hprof ... [junit4] Heap dump file created [556370375 bytes in 7.665 secs] [junit4] <<< JVM J2: EOF ---- [...truncated 14 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003: There were test failures: 533 suites, 2104 tests, 2 errors, 26 ignored (14 assumptions) Total time: 173 minutes 17 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org