Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/959/
1 tests failed. FAILED: org.apache.solr.cloud.UnloadDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=38456, name=testExecutor-5346-thread-1, state=RUNNABLE, group=TGRP-UnloadDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=38456, name=testExecutor-5346-thread-1, state=RUNNABLE, group=TGRP-UnloadDistributedZkTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:50721/mx_z/q at __randomizedtesting.SeedInfo.seed([E88BA7A2FD789FBD]:0) at org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$3(BasicDistributedZkTest.java:583) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$6(ExecutorUtil.java:229) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:50721/mx_z/q at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588) 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.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.BasicDistributedZkTest.lambda$createCores$3(BasicDistributedZkTest.java:581) ... 4 more Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482) ... 8 more Build Log: [...truncated 11781 lines...] [junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/init-core-data-001 [junit4] 2> 2105017 INFO (SUITE-UnloadDistributedZkTest-seed#[E88BA7A2FD789FBD]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /mx_z/q [junit4] 2> 2105022 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2105033 INFO (Thread-29375) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2105033 INFO (Thread-29375) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2105133 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.ZkTestServer start zk server on port:48713 [junit4] 2> 2105133 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2105157 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2105172 INFO (zkCallback-2399-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@185518b name:ZooKeeperConnection Watcher:127.0.0.1:48713 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2105172 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2105172 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2105172 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2105175 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2105193 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2105221 INFO (zkCallback-2400-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7a68c4c8 name:ZooKeeperConnection Watcher:127.0.0.1:48713/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2105221 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2105221 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2105222 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 2105223 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 2105224 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 2105225 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 2105226 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 2105226 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2105228 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 2105228 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 2105229 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2105229 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2105230 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 2105231 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 2105232 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 2105232 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 2105233 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 2105233 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 2105234 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 2105234 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2105235 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 2105235 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2105236 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2105237 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2105238 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 2105238 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2105239 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 2105239 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 2105412 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1 [junit4] 2> 2105426 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 2105501 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6c2185c3{/mx_z/q,null,AVAILABLE} [junit4] 2> 2105502 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5cb61a3a{HTTP/1.1,[http/1.1]}{127.0.0.1:49203} [junit4] 2> 2105502 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.e.j.s.Server Started @2108716ms [junit4] 2> 2105502 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/tempDir-001/control/data, hostContext=/mx_z/q, hostPort=49203, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores} [junit4] 2> 2105502 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 2105502 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001' [junit4] 2> 2105502 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2105502 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2105503 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2105518 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2105537 INFO (zkCallback-2401-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4e59eb00 name:ZooKeeperConnection Watcher:127.0.0.1:48713/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2105537 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2105538 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2105539 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2105539 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/solr.xml [junit4] 2> 2105543 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores [junit4] 2> 2105544 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.CoreContainer New CoreContainer 1973411439 [junit4] 2> 2105544 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001] [junit4] 2> 2105544 WARN (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/lib [junit4] 2> 2105549 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] 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> 2105550 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2105551 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2105551 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2105551 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48713/solr [junit4] 2> 2105551 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2105551 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2105566 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2105597 INFO (zkCallback-2404-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@691f5bd0 name:ZooKeeperConnection Watcher:127.0.0.1:48713 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2105599 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2105599 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2105621 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2105637 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a6bfc36 name:ZooKeeperConnection Watcher:127.0.0.1:48713/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2105637 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2105638 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2105640 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 2105642 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 2105643 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 2105645 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 2105646 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2105647 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2105648 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2105648 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 2105649 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2105650 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 2105651 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2105652 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2105653 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2105653 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000 [junit4] 2> 2105653 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49203_mx_z%2Fq [junit4] 2> 2105653 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 2105654 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.Overseer Overseer (id=95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) starting [junit4] 2> 2105655 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 2105659 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2105669 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 2105670 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 2105670 INFO (OverseerCollectionConfigSetProcessor-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2105675 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49203_mx_z%2Fq [junit4] 2> 2105675 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:49203_mx_z%2Fq [junit4] 2> 2105676 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 2105681 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2105705 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2105705 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2105706 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores [junit4] 2> 2105707 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] 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> 2105707 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1 [junit4] 2> 2105707 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2105718 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2105718 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2105719 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2105734 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2 [junit4] 2> 2105734 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2105734 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49203/mx_z/q";, [junit4] 2> "node_name":"127.0.0.1:49203_mx_z%2Fq", [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> 2105734 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49203/mx_z/q";, [junit4] 2> "node_name":"127.0.0.1:49203_mx_z%2Fq", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2105735 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 2105735 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 2105736 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] 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> 2106720 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2106720 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 2106720 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2106720 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 2106721 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 2106721 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1' [junit4] 2> 2106721 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2106721 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2106722 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2106727 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2106730 WARN (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 2106731 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2106751 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2106762 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2106994 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2107005 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2107006 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2107018 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2107020 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2107027 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2107027 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2107029 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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-master/solr/build/solr-core/test/J2 [junit4] 2> 2107029 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2107029 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2107029 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1/data/] [junit4] 2> 2107029 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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@12f8e173 [junit4] 2> 2107030 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1/data [junit4] 2> 2107037 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1/data/index/ [junit4] 2> 2107037 WARN (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2107038 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1/data/index [junit4] 2> 2107038 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=6, maxMergeAtOnceExplicit=2, maxMergedSegmentMB=1.5927734375, floorSegmentMB=0.71875, forceMergeDeletesPctAllowed=16.97948485593951, segmentsPerTier=41.0, maxCFSSegmentSizeMB=1.052734375, noCFSRatio=0.13986048224984948 [junit4] 2> 2107038 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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@3d0593d3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c4cb00f),segFN=segments_1,generation=1} [junit4] 2> 2107038 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2107043 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2107043 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 2107043 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2107043 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2107043 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2107043 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107043 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107044 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107044 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107044 WARN (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107046 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2107047 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2107047 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2107048 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2107053 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:49203_mx_z%2Fq 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-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1/data/ [junit4] 2> 2107054 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 2107056 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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/ping,/admin/threads,/update,/admin/file [junit4] 2> 2107056 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107060 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107060 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107061 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2107061 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2107065 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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=30, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2107066 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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@3d0593d3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c4cb00f),segFN=segments_1,generation=1} [junit4] 2> 2107066 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2107066 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@19a98282[collection1] main] [junit4] 2> 2107066 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107067 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107067 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107067 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2107067 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 2107068 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107068 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107068 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107068 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2107068 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2107068 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107068 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107069 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107069 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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 1528700852672397312 [junit4] 2> 2107071 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq 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 1528700852672397312 [junit4] 2> 2107071 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 2107071 INFO (coreLoadExecutor-5267-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2107072 INFO (searcherExecutor-5268-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@19a98282[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2107077 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:49203/mx_z/q collection:control_collection shard:shard1 [junit4] 2> 2107088 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107090 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq 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/95543803159117828-core_node1-n_0000000000 [junit4] 2> 2107091 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107092 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2107092 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107092 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49203/mx_z/q/collection1/ [junit4] 2> 2107092 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2107092 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:49203/mx_z/q/collection1/ has no replicas [junit4] 2> 2107093 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] 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> 2107093 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2107095 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95543803159117828-core_node1-n_0000000000 [junit4] 2> 2107097 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49203/mx_z/q/collection1/ shard1 [junit4] 2> 2107104 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:49203/mx_z/q";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 2107206 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] 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> 2107235 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2107246 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2107249 INFO (zkCallback-2407-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@371fa952 name:ZooKeeperConnection Watcher:127.0.0.1:48713/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2107249 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2107250 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2107250 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2107251 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2107251 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2107252 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 2107252 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2107255 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:49203/mx_z/q/collection1/ and leader is http://127.0.0.1:49203/mx_z/q/collection1/ [junit4] 2> 2107255 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49203/mx_z/q [junit4] 2> 2107255 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2107255 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 2107255 INFO (coreZkRegister-5261-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:49203_mx_z%2Fq 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> 2107257 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49203/mx_z/q";, [junit4] 2> "node_name":"127.0.0.1:49203_mx_z%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 2107257 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:49203/mx_z/q";, [junit4] 2> "node_name":"127.0.0.1:49203_mx_z%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2107269 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2107281 INFO (zkCallback-2408-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78f59432 name:ZooKeeperConnection Watcher:127.0.0.1:48713/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2107281 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2107281 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2107283 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] 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: 2 [junit4] 2> 2107283 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 2107285 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] 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> 2107289 INFO (zkCallback-2407-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 2107377 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/cores/collection1 [junit4] 2> 2107377 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001 [junit4] 2> 2107378 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 2107380 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@147411af{/mx_z/q,null,AVAILABLE} [junit4] 2> 2107380 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.e.j.s.ServerConnector Started ServerConnector@20b8264d{HTTP/1.1,[http/1.1]}{127.0.0.1:52523} [junit4] 2> 2107380 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.e.j.s.Server Started @2110594ms [junit4] 2> 2107380 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/mx_z/q, hostPort=52523, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/cores} [junit4] 2> 2107380 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 2107381 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001' [junit4] 2> 2107381 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2107381 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2107381 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2107381 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2107383 INFO (zkCallback-2409-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28902e5 name:ZooKeeperConnection Watcher:127.0.0.1:48713/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2107383 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2107384 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2107385 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2107385 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/solr.xml [junit4] 2> 2107389 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 2107391 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/cores [junit4] 2> 2107391 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.CoreContainer New CoreContainer 1442872254 [junit4] 2> 2107391 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001] [junit4] 2> 2107391 WARN (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/lib [junit4] 2> 2107391 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] 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> 2107392 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2107393 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2107393 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2107393 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48713/solr [junit4] 2> 2107393 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2107393 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2107397 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2107398 INFO (zkCallback-2412-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@760c0aeb name:ZooKeeperConnection Watcher:127.0.0.1:48713 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2107399 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2107399 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2107401 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2107402 INFO (zkCallback-2413-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@356c88ad name:ZooKeeperConnection Watcher:127.0.0.1:48713/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2107402 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2107407 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2107408 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2107410 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2107410 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95543803159117833-127.0.0.1:52523_mx_z%2Fq-n_0000000001 [junit4] 2> 2107411 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000 to know if I could be the leader [junit4] 2> 2107411 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.ZkController Publish node=127.0.0.1:52523_mx_z%2Fq as DOWN [junit4] 2> 2107413 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:52523_mx_z%2Fq"} current state version: 3 [junit4] 2> 2107413 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:52523_mx_z%2Fq [junit4] 2> 2107414 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 2107517 INFO (zkCallback-2407-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1]) [junit4] 2> 2107517 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] 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> 2107517 INFO (zkCallback-2413-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq ] 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> 2108413 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52523_mx_z%2Fq [junit4] 2> 2108414 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:52523_mx_z%2Fq [junit4] 2> 2108414 INFO (zkCallback-2407-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> 2108414 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] 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> 2108415 INFO (zkCallback-2407-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2108415 INFO (zkCallback-2405-thread-1-processing-n:127.0.0.1:49203_mx_z%2Fq) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2108415 INFO (zkCallback-2413-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq ] 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> 2108415 INFO (zkCallback-2413-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2108434 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2108434 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2108446 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/cores [junit4] 2> 2108446 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] 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> 2108447 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/cores/collection1 [junit4] 2> 2108447 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2108465 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2108465 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2108467 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52523/mx_z/q";, [junit4] 2> "node_name":"127.0.0.1:52523_mx_z%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 2108467 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:52523/mx_z/q";, [junit4] 2> "node_name":"127.0.0.1:52523_mx_z%2Fq", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 2108467 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 2108467 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 2108468 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 2108468 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 2108468 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [1] [junit4] 2> 2108468 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2108469 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2 [junit4] 2> 2108469 INFO (TEST-UnloadDistributedZkTest.test-seed#[E88BA7A2FD789FBD]) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2108569 INFO (OverseerStateUpdate-95543803159117828-127.0.0.1:49203_mx_z%2Fq-n_0000000000) [n:127.0.0.1:49203_mx_z%2Fq ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 2108570 INFO (zkCallback-2413-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq ] 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> 2108570 INFO (zkCallback-2413-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq ] o.a.s.c.c.ZkStateReader Updating data for [collection1] from [1] to [2] [junit4] 2> 2109468 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2109469 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 2109469 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2109469 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 2109470 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 2109470 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E88BA7A2FD789FBD-001/shard-1-001/cores/collection1' [junit4] 2> 2109470 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2109470 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2109472 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2109478 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2109481 WARN (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 2109482 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2109490 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2109495 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2109598 INFO (coreLoadExecutor-5278-thread-1-processing-n:127.0.0.1:52523_mx_z%2Fq) [n:127.0.0.1:52523_mx_z%2Fq c:collection1 x: [...truncated too long message...] ns/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/build/analysis/icu/lucene-analyzers-icu-7.0.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/contrib/analysis-extras/lib/icu4j-56.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.2.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/temp/junit4-J1-20160313_145030_184.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/temp/junit4-J1-20160313_145030_184.suites -stdin [junit4] ERROR: JVM J1 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1501) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] at java.lang.Thread.run(Thread.java:745) BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1014: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. Total time: 361 minutes 41 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
