Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/730/
2 tests failed. REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=4728, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=4728, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:42283: collection already exists: awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([70084DC6BDC62AB3]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=78408, name=collection3, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=78408, name=collection3, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:57637/jx_s: Could not find collection : awholynewstresscollection_collection3_0 at __randomizedtesting.SeedInfo.seed([70084DC6BDC62AB3]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) Build Log: [...truncated 11161 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/init-core-data-001 [junit4] 2> 1347004 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[70084DC6BDC62AB3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) [junit4] 2> 1347005 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[70084DC6BDC62AB3]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1347015 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1347016 INFO (Thread-1889) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1347016 INFO (Thread-1889) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1347116 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.ZkTestServer start zk server on port:44979 [junit4] 2> 1347136 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1347138 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1347140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1347141 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1347143 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1347144 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1347145 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1347147 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1347148 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1347150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1347151 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1347501 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1 [junit4] 2> 1347502 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1347504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28d68c3{/,null,AVAILABLE} [junit4] 2> 1347505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@57c0f022{HTTP/1.1}{127.0.0.1:55300} [junit4] 2> 1347505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.e.j.s.Server Started @1350464ms [junit4] 2> 1347505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/tempDir-001/control/data, hostContext=/, hostPort=55300, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores} [junit4] 2> 1347509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 1347509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/' [junit4] 2> 1347525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/solr.xml [junit4] 2> 1347531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores [junit4] 2> 1347531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.CoreContainer New CoreContainer 135345230 [junit4] 2> 1347531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/] [junit4] 2> 1347531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/lib [junit4] 2> 1347531 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/lib). [junit4] 2> 1347538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] 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> 1347539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1347540 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1347540 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1347540 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44979/solr [junit4] 2> 1347541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1347563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55300_ [junit4] 2> 1347566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1347566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94114705392861187-127.0.0.1:55300_-n_0000000000 [junit4] 2> 1347567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55300_ [junit4] 2> 1347568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer Overseer (id=94114705392861187-127.0.0.1:55300_-n_0000000000) starting [junit4] 2> 1347573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1347575 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1347575 INFO (OverseerCollectionProcessor-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 1347584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1347584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1347585 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores [junit4] 2> 1347586 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1347586 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/ [junit4] 2> 1347586 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1347587 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1347587 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1347588 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1347593 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1347594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1347594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:55300_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1347595 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55300", [junit4] 2> "node_name":"127.0.0.1:55300_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1347595 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55300", [junit4] 2> "node_name":"127.0.0.1:55300_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1347595 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1347595 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1348588 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1348589 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1348590 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1348590 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/' [junit4] 2> 1348608 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1348615 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1348622 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1348635 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1348637 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1348642 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1348747 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1348755 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1348756 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1348767 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1348770 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1348772 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1348773 WARN (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1348774 WARN (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1348774 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1348775 WARN (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1348775 WARN (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1348775 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1348775 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1348775 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1348776 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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@367ab2bb [junit4] 2> 1348776 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/data [junit4] 2> 1348777 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/data/index/ [junit4] 2> 1348777 WARN (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1348777 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/data/index [junit4] 2> 1348777 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=16.9306640625, floorSegmentMB=0.3232421875, forceMergeDeletesPctAllowed=17.432587382193233, segmentsPerTier=2.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18696305547791658 [junit4] 2> 1348778 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@5d5b426c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ed197e0),segFN=segments_1,generation=1} [junit4] 2> 1348778 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1348779 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/data/ [junit4] 2> 1348779 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1348782 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1348782 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1348783 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1348783 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1348783 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1348783 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348784 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348784 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348784 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348786 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1348787 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1348788 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1348789 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1348792 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1348793 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348794 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1348794 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1348795 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1348795 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1348796 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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=1810676404, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1348797 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@5d5b426c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ed197e0),segFN=segments_1,generation=1} [junit4] 2> 1348797 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1348797 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1f4e8d10[collection1] main [junit4] 2> 1348798 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348798 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1348798 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1348799 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348799 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348799 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348799 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1348800 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1348800 INFO (searcherExecutor-1711-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f4e8d10[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1348800 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348800 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 1348801 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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> 1348801 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ 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 1505835288152244224 [junit4] 2> 1348803 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 3 ms to seed version buckets with highest version 1505835288152244224 [junit4] 2> 1348803 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1348804 INFO (coreLoadExecutor-1709-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1348805 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ 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:55300 collection:control_collection shard:shard1 [junit4] 2> 1348811 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ 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/94114705392861187-core_node1-n_0000000000 [junit4] 2> 1348812 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1348813 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1348813 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1348813 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ 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> 1348813 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] 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> 1348814 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55300/collection1/ [junit4] 2> 1348814 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1348814 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:55300/collection1/ has no replicas [junit4] 2> 1348814 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ 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:55300/collection1/ shard1 [junit4] 2> 1348821 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1348822 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] 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:55300", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1348973 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:55300/collection1/ and leader is http://127.0.0.1:55300/collection1/ [junit4] 2> 1348973 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ 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:55300 [junit4] 2> 1348973 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1348974 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1348974 INFO (coreZkRegister-1704-thread-1-processing-n:127.0.0.1:55300_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55300_ 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> 1348975 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1348976 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55300", [junit4] 2> "node_name":"127.0.0.1:55300_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1348977 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55300", [junit4] 2> "node_name":"127.0.0.1:55300_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1349102 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1349102 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1349106 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1349107 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] 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: 3 [junit4] 2> 1349107 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1349209 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1349432 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1 [junit4] 2> 1349433 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001 [junit4] 2> 1349437 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1349440 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3fa4616{/,null,AVAILABLE} [junit4] 2> 1349441 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6be67ad3{HTTP/1.1}{127.0.0.1:51369} [junit4] 2> 1349441 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.e.j.s.Server Started @1352400ms [junit4] 2> 1349441 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=51369, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores} [junit4] 2> 1349442 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 1349442 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/' [junit4] 2> 1349460 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/solr.xml [junit4] 2> 1349466 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores [junit4] 2> 1349467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.CoreContainer New CoreContainer 1445482402 [junit4] 2> 1349467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/] [junit4] 2> 1349467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/lib [junit4] 2> 1349467 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/lib). [junit4] 2> 1349475 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] 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> 1349476 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1349477 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1349477 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1349477 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44979/solr [junit4] 2> 1349477 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1350492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51369_ [junit4] 2> 1350496 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1350496 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94114705392861191-127.0.0.1:51369_-n_0000000001 [junit4] 2> 1350497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94114705392861187-127.0.0.1:55300_-n_0000000000 to know if I could be the leader [junit4] 2> 1350497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1350498 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1350498 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores [junit4] 2> 1350499 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1350499 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/ [junit4] 2> 1350499 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1350501 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1350501 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[70084DC6BDC62AB3]) [n:127.0.0.1:51369_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1350501 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1350502 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1350503 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1350503 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1 [junit4] 2> 1350504 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51369", [junit4] 2> "node_name":"127.0.0.1:51369_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 1350504 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51369", [junit4] 2> "node_name":"127.0.0.1:51369_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1350504 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 1350504 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1350506 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1350607 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1351506 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1351506 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1351507 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1351507 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/' [junit4] 2> 1351521 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1351525 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1351530 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1351538 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1351539 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1351544 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1351638 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1351644 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1351645 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1351659 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1351661 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1351664 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1351665 WARN (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1351665 WARN (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1351665 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1351666 WARN (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1351666 WARN (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1351667 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1351667 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1351667 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/], dataDir=[null] [junit4] 2> 1351667 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@367ab2bb [junit4] 2> 1351668 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/data [junit4] 2> 1351668 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 1351668 WARN (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1351668 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/data/index [junit4] 2> 1351669 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=16.9306640625, floorSegmentMB=0.3232421875, forceMergeDeletesPctAllowed=17.432587382193233, segmentsPerTier=2.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18696305547791658 [junit4] 2> 1351670 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@38969c50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263d7e7b),segFN=segments_1,generation=1} [junit4] 2> 1351670 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1351673 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/data/ [junit4] 2> 1351674 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_70084DC6BDC62AB3-001/shard-1-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1351677 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1351677 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1351678 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1351678 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1351678 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1351678 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1351678 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1351678 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1351679 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1351680 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1351682 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1351683 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1351683 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1351690 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1351690 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1351691 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1351691 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1351692 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1351692 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1351694 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1810676404, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1351694 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@38969c50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263d7e7b),segFN=segments_1,generation=1} [junit4] 2> 1351694 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1351695 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@53c55b4f[collection1] main [junit4] 2> 1351697 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1351697 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1351698 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1351698 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1351698 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 1351698 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1351699 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1351699 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1351699 INFO (searcherExecutor-1722-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@53c55b4f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1351700 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 1351700 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 1351700 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 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> 1351700 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1505835291192066048 [junit4] 2> 1351702 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1505835291192066048 [junit4] 2> 1351703 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1351703 INFO (coreLoadExecutor-1721-thread-1-processing-n:127.0.0.1:51369_) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1351704 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:51369 collection:collection1 shard:shard2 [junit4] 2> 1351708 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/collection1/leader_elect/shard2/election/94114705392861191-core_node1-n_0000000000 [junit4] 2> 1351709 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard2 [junit4] 2> 1351711 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1351712 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 1351713 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 1351713 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1351714 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1351718 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51369/collection1/ [junit4] 2> 1351719 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1351720 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51369/collection1/ has no replicas [junit4] 2> 1351720 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:51369/collection1/ shard2 [junit4] 2> 1351725 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1351725 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:51369", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 1351726 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 1351730 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:51369/collection1/ and leader is http://127.0.0.1:51369/collection1/ [junit4] 2> 1351730 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51369 [junit4] 2> 1351730 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1351730 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1351730 INFO (coreZkRegister-1716-thread-1-processing-n:127.0.0.1:51369_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:51369_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1351731 INFO (zkCallback-401-thread-1-processing-n:127.0.0.1:55300_) [n:127.0.0.1:55300_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1351732 INFO (OverseerStateUpdate-94114705392861187-127.0.0.1:55300_-n_0000000000) [n:127.0.0.1:55300_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection [...truncated too long message...] mx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:483) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 5051500 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[70084DC6BDC62AB3]-worker) [n:127.0.0.1:36894_jx_s c:addReplicaColl s:shard1 r:core_node5 x:addReplicaColl_shard1_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Jul 05, 2015 3:09:02 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene53, sim=DefaultSimilarity, locale=ms, timezone=America/Marigot [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=4,threads=2,free=95635048,total=535298048 [junit4] 2> NOTE: All tests run in this JVM: [TestExactSharedStatsCache, CollectionReloadTest, SolrInfoMBeanTest, TestImplicitCoreProperties, DirectUpdateHandlerOptimizeTest, TestBM25SimilarityFactory, TestSolrCloudWithKerberosAlt, UnloadDistributedZkTest, TestSolrQueryParser, ResponseLogComponentTest, SuggesterTest, TestFieldCollectionResource, TestFastOutputStream, FileBasedSpellCheckerTest, TestSolr4Spatial2, TestPartialUpdateDeduplication, RuleEngineTest, HdfsBasicDistributedZk2Test, TestStressReorder, TestDynamicFieldCollectionResource, ReplicationFactorTest, DistributedQueryElevationComponentTest, MultiThreadedOCPTest, MoreLikeThisHandlerTest, FullSolrCloudDistribCmdsTest, DistributedQueryComponentOptimizationTest, TestExactStatsCache, TestShortCircuitedRequests, TestReplicationHandlerBackup, OutputWriterTest, TestManagedStopFilterFactory, SoftAutoCommitTest, RecoveryAfterSoftCommitTest, OverseerTest, TestInitParams, DistributedSuggestComponentTest, OverseerCollectionProcessorTest, TestCollationField, AutoCommitTest, DistribJoinFromCollectionTest, PolyFieldTest, TestHdfsUpdateLog, TestCollectionAPI, TestMiniSolrCloudClusterSSL, BasicDistributedZk2Test, TestSchemaSimilarityResource, TestTrie, TestLuceneMatchVersion, TestRecovery, TestManagedResource, HdfsRecoveryZkTest, TestSystemIdResolver, SpellCheckCollatorTest, TestFieldTypeCollectionResource, SolrIndexSplitterTest, TestStressRecovery, TestAuthorizationFramework, SimpleFacetsTest, ActionThrottleTest, BadIndexSchemaTest, TestHashQParserPlugin, TestDocSet, TestRequestStatusCollectionAPI, OverriddenZkACLAndCredentialsProvidersTest, BinaryUpdateRequestHandlerTest, TestLRUStatsCache, SearchHandlerTest, TestReloadAndDeleteDocs, ChangedSchemaMergeTest, HdfsLockFactoryTest, TestDefaultSimilarityFactory, StressHdfsTest, HdfsSyncSliceTest, RecoveryZkTest, DirectUpdateHandlerTest, TestReload, LoggingHandlerTest, TestSort, SOLR749Test, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, TestPostingsSolrHighlighter, TestPhraseSuggestions, SynonymTokenizerTest, TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, TimeZoneUtilsTest, ScriptEngineTest, PreAnalyzedFieldTest, PrimUtilsTest, TestSuggestSpellingConverter, DOMUtilTest, TestSolrJ, TestUtils, TestDocumentBuilder, SystemInfoHandlerTest, UUIDFieldTest, CircularListTest, CursorPagingTest, DistributedIntervalFacetingTest, AsyncCallRequestStatusResponseTest, AsyncMigrateRouteKeyTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DistributedVersionInfoTest, OutOfBoxZkACLAndCredentialsProvidersTest, RemoteQueryErrorTest, RollingRestartTest, TestCloudPivotFacet, TestLeaderElectionZkExpiry, TestMiniSolrCloudCluster, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [514/514] on J2 in 1425.76s, 1 test, 1 error <<< FAILURES! [...truncated 12 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:513: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: There were test failures: 514 suites, 2022 tests, 2 errors, 20 ignored (10 assumptions) Total time: 200 minutes 21 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org