Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/895/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=22616, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=22616, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:46930, https://127.0.0.1:59010, https://127.0.0.1:42959, https://127.0.0.1:40538, https://127.0.0.1:56851] at __randomizedtesting.SeedInfo.seed([8AF97C780B82D319]:0) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:891) Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:46930, https://127.0.0.1:59010, https://127.0.0.1:42959, https://127.0.0.1:40538, https://127.0.0.1:56851] at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42959: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr- at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) ... 7 more Build Log: [...truncated 10867 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_8AF97C780B82D319-001/init-core-data-001 [junit4] 2> 1557779 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[8AF97C780B82D319]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 1557779 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[8AF97C780B82D319]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1557784 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1557784 INFO (Thread-13787) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1557784 INFO (Thread-13787) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1557885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.ZkTestServer start zk server on port:54894 [junit4] 2> 1557885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1557885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1557888 INFO (zkCallback-2464-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@488a36d9 name:ZooKeeperConnection Watcher:127.0.0.1:54894 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1557888 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1557888 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1557888 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1557895 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1557895 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1557896 INFO (zkCallback-2465-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7446a2cf name:ZooKeeperConnection Watcher:127.0.0.1:54894/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1557896 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1557896 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1557896 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1557898 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1557899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1557903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1557904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1557906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1557908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1557910 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557910 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1557911 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557911 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1557913 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557913 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1557914 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557915 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1557916 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557916 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1557918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1557919 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557919 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1557921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1557921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1557923 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1557923 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1557924 INFO (zkCallback-2466-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@50c2130c name:ZooKeeperConnection Watcher:127.0.0.1:54894/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1557924 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1557924 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1558249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/control-001/cores/collection1 [junit4] 2> 1558252 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1558253 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@24725791{/,null,AVAILABLE} [junit4] 2> 1558254 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.u.s.SslContextFactory x509=X509@287a6f2c(solrtest,h=[],w=[]) for SslContextFactory@40dd7394(file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/server/etc/test/solrtest.keystore,file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/server/etc/test/solrtest.keystore) [junit4] 2> 1558256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.s.ServerConnector Started ServerConnector@74f6f09b{SSL,[ssl, http/1.1]}{127.0.0.1:42959} [junit4] 2> 1558256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.s.Server Started @1561530ms [junit4] 2> 1558257 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/tempDir-001/control/data, hostContext=/, hostPort=42959, 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_8AF97C780B82D319-001/control-001/cores} [junit4] 2> 1558258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1558258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/control-001' [junit4] 2> 1558258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1558258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1558258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1558259 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1558259 INFO (zkCallback-2467-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@163b032 name:ZooKeeperConnection Watcher:127.0.0.1:54894/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1558260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1558260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1558261 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1558261 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/control-001/solr.xml [junit4] 2> 1558265 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.CorePropertiesLocator 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_8AF97C780B82D319-001/control-001/cores [junit4] 2> 1558266 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.CoreContainer New CoreContainer 1350153798 [junit4] 2> 1558266 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/control-001] [junit4] 2> 1558266 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8AF97C780B82D319-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8AF97C780B82D319-001/control-001/lib [junit4] 2> 1558266 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1558268 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1558269 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1558269 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1558269 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54894/solr [junit4] 2> 1558269 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1558269 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1558270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1558271 INFO (zkCallback-2470-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c7ba905 name:ZooKeeperConnection Watcher:127.0.0.1:54894 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1558271 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1558272 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1558273 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1558274 INFO (zkCallback-2471-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@22e58e02 name:ZooKeeperConnection Watcher:127.0.0.1:54894/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1558274 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1558275 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1558277 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1558279 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1558281 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1558283 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1558285 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1558286 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1558287 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1558290 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1558291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42959_ [junit4] 2> 1558291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:42959_ [junit4] 2> 1558293 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1558295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1558297 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1558297 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95112366953725957-127.0.0.1:42959_-n_0000000000 [junit4] 2> 1558297 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42959_ [junit4] 2> 1558297 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1558299 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.Overseer Overseer (id=95112366953725957-127.0.0.1:42959_-n_0000000000) starting [junit4] 2> 1558300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1558305 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1558305 INFO (OverseerCollectionConfigSetProcessor-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1558306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1558306 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1558332 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1558332 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1558332 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.CorePropertiesLocator 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_8AF97C780B82D319-001/control-001/cores [junit4] 2> 1558333 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1558334 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.CorePropertiesLocator 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_8AF97C780B82D319-001/control-001/cores/collection1 [junit4] 2> 1558334 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1558335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1558335 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:42959_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1558335 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1558335 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1558336 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:42959";, [junit4] 2> "node_name":"127.0.0.1:42959_", [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> 1558337 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1558338 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:42959";, [junit4] 2> "node_name":"127.0.0.1:42959_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1558338 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1558338 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1558340 INFO (zkCallback-2471-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1559338 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1559338 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1559339 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1559339 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1559339 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1559339 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_8AF97C780B82D319-001/control-001/cores/collection1' [junit4] 2> 1559340 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1559340 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1559341 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1559346 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1559349 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1559357 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1559362 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1559449 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1559454 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1559455 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1559462 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1559464 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1559465 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1559466 WARN (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1559466 WARN (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1559466 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1559467 WARN (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1559467 WARN (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1559468 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1559468 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1559468 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1559468 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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/temp/solr.cloud.CollectionsAPIDistributedZkTest_8AF97C780B82D319-001/control-001/cores/collection1], dataDir=[/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_8AF97C780B82D319-001/control-001/cores/collection1/data/] [junit4] 2> 1559468 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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@38ba9796 [junit4] 2> 1559469 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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_8AF97C780B82D319-001/control-001/cores/collection1/data [junit4] 2> 1559469 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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_8AF97C780B82D319-001/control-001/cores/collection1/data/index/ [junit4] 2> 1559469 WARN (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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_8AF97C780B82D319-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1559470 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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_8AF97C780B82D319-001/control-001/cores/collection1/data/index [junit4] 2> 1559470 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=3, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5015402840642721] [junit4] 2> 1559470 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5819001c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52fb86c0),segFN=segments_1,generation=1} [junit4] 2> 1559470 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1559471 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42959_ 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_8AF97C780B82D319-001/control-001/cores/collection1/data/ [junit4] 2> 1559471 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42959_ 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_8AF97C780B82D319-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1559474 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1559474 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1559474 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1559474 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1559474 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1559474 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559475 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559475 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559475 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559475 WARN (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 1559476 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1559477 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1559478 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1559479 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1559482 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1559482 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559483 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559483 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559484 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1559484 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1559484 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=2, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1559484 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5819001c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52fb86c0),segFN=segments_1,generation=1} [junit4] 2> 1559484 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1559484 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@106834df[collection1] main [junit4] 2> 1559485 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1559485 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1559485 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559486 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1559486 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1559486 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559486 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559486 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559486 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1559487 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1559487 INFO (searcherExecutor-6003-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@106834df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1559487 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559487 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559487 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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> 1559487 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ 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 1521797873044291584 [junit4] 2> 1559489 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1521797873044291584 [junit4] 2> 1559489 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1559490 INFO (coreLoadExecutor-6002-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1559490 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:42959 collection:control_collection shard:shard1 [junit4] 2> 1559491 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 1559494 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ 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/95112366953725957-core_node1-n_0000000000 [junit4] 2> 1559494 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000 [junit4] 2> 1559496 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1559496 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ 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> 1559496 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] 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> 1559496 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42959/collection1/ [junit4] 2> 1559496 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1559496 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:42959/collection1/ has no replicas [junit4] 2> 1559497 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1559499 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1559501 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:42959/collection1/ shard1 [junit4] 2> 1559501 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"https://127.0.0.1:42959";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1559603 INFO (zkCallback-2471-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1559652 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:42959/collection1/ and leader is https://127.0.0.1:42959/collection1/ [junit4] 2> 1559652 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:42959 [junit4] 2> 1559652 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1559652 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1559652 INFO (coreZkRegister-5996-thread-1-processing-n:127.0.0.1:42959_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42959_ 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> 1559654 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:42959";, [junit4] 2> "node_name":"127.0.0.1:42959_", [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> 1559654 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:42959";, [junit4] 2> "node_name":"127.0.0.1:42959_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1559757 INFO (zkCallback-2471-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1559838 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1559839 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1559840 INFO (zkCallback-2473-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c0c4be2 name:ZooKeeperConnection Watcher:127.0.0.1:54894/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1559840 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1559840 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1559840 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1559842 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1559842 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1559842 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1559846 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1559846 INFO (zkCallback-2474-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2565db5e name:ZooKeeperConnection Watcher:127.0.0.1:54894/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1559846 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1559846 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1559850 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] 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> 1559850 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1559952 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1560173 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/shard-1-001/cores/collection1 [junit4] 2> 1560173 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/shard-1-001 [junit4] 2> 1560175 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1560176 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22bd1a5d{/,null,AVAILABLE} [junit4] 2> 1560177 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.u.s.SslContextFactory x509=X509@68e63a93(solrtest,h=[],w=[]) for SslContextFactory@2b242602(file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/server/etc/test/solrtest.keystore,file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/server/etc/test/solrtest.keystore) [junit4] 2> 1560178 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2da5611c{SSL,[ssl, http/1.1]}{127.0.0.1:46930} [junit4] 2> 1560179 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.e.j.s.Server Started @1563452ms [junit4] 2> 1560179 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=46930, 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_8AF97C780B82D319-001/shard-1-001/cores} [junit4] 2> 1560180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1560180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/shard-1-001' [junit4] 2> 1560180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1560180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1560180 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1560181 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1560182 INFO (zkCallback-2475-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b107078 name:ZooKeeperConnection Watcher:127.0.0.1:54894/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1560182 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1560182 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1560183 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1560183 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/shard-1-001/solr.xml [junit4] 2> 1560187 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.CorePropertiesLocator 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_8AF97C780B82D319-001/shard-1-001/cores [junit4] 2> 1560188 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.CoreContainer New CoreContainer 1560035836 [junit4] 2> 1560188 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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_8AF97C780B82D319-001/shard-1-001] [junit4] 2> 1560188 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8AF97C780B82D319-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_8AF97C780B82D319-001/shard-1-001/lib [junit4] 2> 1560188 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] 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> 1560190 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1560191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1560191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1560192 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54894/solr [junit4] 2> 1560192 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1560192 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1560192 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1560193 INFO (zkCallback-2478-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6be596bd name:ZooKeeperConnection Watcher:127.0.0.1:54894 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1560194 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1560194 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1560196 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1560196 INFO (zkCallback-2479-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@47ae1e05 name:ZooKeeperConnection Watcher:127.0.0.1:54894/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1560196 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1560201 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1561206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46930_ [junit4] 2> 1561207 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46930_ [junit4] 2> 1561208 INFO (zkCallback-2473-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1561208 INFO (zkCallback-2471-thread-1-processing-n:127.0.0.1:42959_) [n:127.0.0.1:42959_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1561208 INFO (zkCallback-2479-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1561210 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1561210 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95112366953725962-127.0.0.1:46930_-n_0000000001 [junit4] 2> 1561211 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95112366953725957-127.0.0.1:42959_-n_0000000000 to know if I could be the leader [junit4] 2> 1561222 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1561222 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1561223 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.CorePropertiesLocator 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_8AF97C780B82D319-001/shard-1-001/cores [junit4] 2> 1561223 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1561224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.CorePropertiesLocator 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_8AF97C780B82D319-001/shard-1-001/cores/collection1 [junit4] 2> 1561224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1561224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1561224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[8AF97C780B82D319]) [n:127.0.0.1:46930_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1561225 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1561225 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1561226 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 1561226 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 1561227 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:46930";, [junit4] 2> "node_name":"127.0.0.1:46930_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 1561227 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [0] [junit4] 2> 1561227 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1561227 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:46930";, [junit4] 2> "node_name":"127.0.0.1:46930_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1561227 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 1561227 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1561329 INFO (OverseerStateUpdate-95112366953725957-127.0.0.1:42959_-n_0000000000) [n:127.0.0.1:42959_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1561330 INFO (zkCallback-2479-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1561330 INFO (zkCallback-2479-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ ] o.a.s.c.c.ZkStateReader Updating data for [collection1] from [0] to [1] [junit4] 2> 1562227 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1562227 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1562228 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1562228 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 1562228 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1562228 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ 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_8AF97C780B82D319-001/shard-1-001/cores/collection1' [junit4] 2> 1562228 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1562228 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1562230 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1562235 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1562239 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1562259 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1562264 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1562341 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1562356 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1562357 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1562364 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1562366 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1562368 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1562368 WARN (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1562369 WARN (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1562369 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1562369 WARN (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1562369 WARN (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1562370 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1562370 INFO (coreLoadExecutor-6013-thread-1-processing-n:127.0.0.1:46930_) [n:127.0.0.1:46930_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1562370 INF [...truncated too long message...] o.seed([8AF97C780B82D319]:0) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:891) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:46930, https://127.0.0.1:59010, https://127.0.0.1:42959, https://127.0.0.1:40538, https://127.0.0.1:56851] [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42959: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr- [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > ... 7 moreThrowable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=22617, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:46930, https://127.0.0.1:59010, https://127.0.0.1:42959, https://127.0.0.1:40538, https://127.0.0.1:56851] [junit4] > at __randomizedtesting.SeedInfo.seed([8AF97C780B82D319]:0) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:891) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:46930, https://127.0.0.1:59010, https://127.0.0.1:42959, https://127.0.0.1:40538, https://127.0.0.1:56851] [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42959: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr- [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > ... 7 more [junit4] 2> 2158194 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[8AF97C780B82D319]-worker) [n:127.0.0.1:59010_ c:addReplicaColl s:shard1 r:core_node7 x:propertyDotName] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=PostingsFormat(name=Memory doPackFST= false), range_facet_l_dv=PostingsFormat(name=Direct), _version_=PostingsFormat(name=Memory doPackFST= false), multiDefault=PostingsFormat(name=LuceneFixedGap), a_t=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=Memory doPackFST= false), a_i1=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=Direct), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= false), text=FSTOrd50, range_facet_l=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=hu_HU, timezone=Atlantic/Faeroe [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=74825376,total=498597888 [junit4] 2> NOTE: All tests run in this JVM: [TestDistributedGrouping, TestCloudManagedSchemaConcurrent, ResponseHeaderTest, ShardRoutingTest, AtomicUpdatesTest, NumericFieldsTest, DocValuesTest, SolrCmdDistributorTest, ZkCLITest, TestSystemIdResolver, BigEndianAscendingWordDeserializerTest, TestBinaryResponseWriter, SolrIndexSplitterTest, TestNamedUpdateProcessors, DirectSolrSpellCheckerTest, SimplePostToolTest, TestNoOpRegenerator, SuggestComponentContextFilterQueryTest, TestSolrDeletionPolicy2, TestStressVersions, DistributedIntervalFacetingTest, UniqFieldsUpdateProcessorFactoryTest, TestConfigSetsAPIZkFailure, TestRandomMergePolicy, MigrateRouteKeyTest, ConcurrentDeleteAndCreateCollectionTest, TestUpdate, AssignTest, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestSQLHandler, ConvertedLegacyTest, JavabinLoaderTest, TestUseDocValuesAsStored2, TestExactSharedStatsCache, TestSchemaVersionResource, TestHdfsUpdateLog, OpenExchangeRatesOrgProviderTest, ZkStateWriterTest, UpdateRequestProcessorFactoryTest, TestRawResponseWriter, TestSortingResponseWriter, TestPKIAuthenticationPlugin, DistributedDebugComponentTest, RemoteQueryErrorTest, IndexBasedSpellCheckerTest, CachingDirectoryFactoryTest, TestInitQParser, CollectionStateFormat2Test, TermsComponentTest, TestSchemaNameResource, TestQueryUtils, DistributedFacetPivotWhiteBoxTest, BasicFunctionalityTest, ExplicitHLLTest, TestSolrCoreProperties, TestMacros, HdfsCollectionsAPIDistributedZkTest, TestRebalanceLeaders, TestScoreJoinQPNoScore, SchemaVersionSpecificBehaviorTest, SpellingQueryConverterTest, WordBreakSolrSpellCheckerTest, LeaderFailoverAfterPartitionTest, TestDynamicFieldResource, TestRequestStatusCollectionAPI, HighlighterTest, TriLevelCompositeIdRoutingTest, SoftAutoCommitTest, TestCopyFieldCollectionResource, DistributedSuggestComponentTest, TestNRTOpen, TestCollectionAPI, RequestLoggingTest, TestHighFrequencyDictionaryFactory, CSVRequestHandlerTest, SolrInfoMBeanTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestJettySolrRunner, ShardRoutingCustomTest, TestSolrQueryParserResource, TestCollapseQParserPlugin, CdcrRequestHandlerTest, NotRequiredUniqueKeyTest, TestBM25SimilarityFactory, TestDownShardTolerantSearch, TermVectorComponentTest, TestPartialUpdateDeduplication, DistributedTermsComponentTest, TestJsonFacets, DocumentBuilderTest, CustomCollectionTest, CollectionsAPIDistributedZkTest] [junit4] Completed [295/573 (1!)] on J1 in 601.45s, 1 test, 1 error <<< FAILURES! [...truncated 895 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:791: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:728: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1436: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:991: There were test failures: 573 suites (8 ignored), 2282 tests, 1 error, 33 ignored (18 assumptions) [seed: 8AF97C780B82D319] Total time: 215 minutes 48 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
