Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/964/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsUnloadDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=113603, name=testExecutor-8999-thread-3, state=RUNNABLE, group=TGRP-HdfsUnloadDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=113603, name=testExecutor-8999-thread-3, state=RUNNABLE, group=TGRP-HdfsUnloadDistributedZkTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:39699/ojyv/zy at __randomizedtesting.SeedInfo.seed([ED5B5F3CAA587275]: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:39699/ojyv/zy 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 12518 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsUnloadDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/init-core-data-001 [junit4] 2> 3960719 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ojyv/zy [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 3960753 WARN (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 3960758 WARN (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3960759 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3960775 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_60048_hdfs____.46yq3g/webapp [junit4] 2> 3960884 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3961173 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:60048 [junit4] 2> 3961236 WARN (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3961237 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3961249 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_41971_datanode____nn75lp/webapp [junit4] 2> 3961342 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3961605 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:41971 [junit4] 2> 3961639 WARN (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3961640 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3961653 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_45659_datanode____z1fkww/webapp [junit4] 2> 3961690 INFO (IPC Server handler 3 on 51467) [ ] BlockStateChange BLOCK* processReport: from storage DS-a3f3ed06-bfbf-473a-b7b3-c48ee1b346fe node DatanodeRegistration(127.0.0.1, datanodeUuid=0315d7d5-e794-4f43-8cd8-c8e11607232c, infoPort=41971, ipcPort=53944, storageInfo=lv=-56;cid=testClusterID;nsid=377778447;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 3961690 INFO (IPC Server handler 3 on 51467) [ ] BlockStateChange BLOCK* processReport: from storage DS-718e1e3e-1fa1-46a6-a1a7-aa125b8bb15a node DatanodeRegistration(127.0.0.1, datanodeUuid=0315d7d5-e794-4f43-8cd8-c8e11607232c, infoPort=41971, ipcPort=53944, storageInfo=lv=-56;cid=testClusterID;nsid=377778447;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 3961759 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3962048 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:45659 [junit4] 2> 3962104 INFO (IPC Server handler 8 on 51467) [ ] BlockStateChange BLOCK* processReport: from storage DS-47bf0818-6b70-4dcb-a633-58544823f981 node DatanodeRegistration(127.0.0.1, datanodeUuid=1ee6d24b-d09d-4a7f-8b58-2eba7445734c, infoPort=45659, ipcPort=40007, storageInfo=lv=-56;cid=testClusterID;nsid=377778447;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 3962104 INFO (IPC Server handler 8 on 51467) [ ] BlockStateChange BLOCK* processReport: from storage DS-205d3e8a-a44f-45fe-9b4c-3004677ea215 node DatanodeRegistration(127.0.0.1, datanodeUuid=1ee6d24b-d09d-4a7f-8b58-2eba7445734c, infoPort=45659, ipcPort=40007, storageInfo=lv=-56;cid=testClusterID;nsid=377778447;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 3962212 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3962215 INFO (Thread-95456) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3962215 INFO (Thread-95456) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 3962313 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.ZkTestServer start zk server on port:44958 [junit4] 2> 3962313 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3962314 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3962316 INFO (zkCallback-4143-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@605c1a56 name:ZooKeeperConnection Watcher:127.0.0.1:44958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3962317 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3962317 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3962317 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 3962320 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3962329 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3962329 INFO (zkCallback-4144-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5430c7b5 name:ZooKeeperConnection Watcher:127.0.0.1:44958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3962329 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3962329 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3962329 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 3962330 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 3962331 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 3962332 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 3962333 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962333 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3962335 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962335 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 3962337 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962337 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3962338 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962338 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 3962339 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962339 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 3962340 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962341 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 3962342 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962342 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3962343 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962343 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3962344 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962345 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3962346 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962346 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3962347 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962347 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 3962471 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/cores/collection1 [junit4] 2> 3962473 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 3962477 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@55d5135b{/ojyv/zy,null,AVAILABLE} [junit4] 2> 3962477 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6cea6efe{HTTP/1.1,[http/1.1]}{127.0.0.1:37828} [junit4] 2> 3962477 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.e.j.s.Server Started @3965566ms [junit4] 2> 3962477 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:51467/hdfs__localhost_51467__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001_tempDir-002_control_data, hostContext=/ojyv/zy, hostPort=37828, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/cores} [junit4] 2> 3962477 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 3962477 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001' [junit4] 2> 3962477 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3962477 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3962478 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3962478 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3962487 INFO (zkCallback-4145-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33d054cb name:ZooKeeperConnection Watcher:127.0.0.1:44958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3962487 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3962487 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3962489 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 3962489 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/solr.xml [junit4] 2> 3962493 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/cores [junit4] 2> 3962493 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.CoreContainer New CoreContainer 1364786091 [junit4] 2> 3962493 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001] [junit4] 2> 3962494 WARN (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/lib [junit4] 2> 3962494 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3962495 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3962495 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3962495 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3962495 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44958/solr [junit4] 2> 3962496 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3962496 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3962496 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3962498 INFO (zkCallback-4148-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@21479604 name:ZooKeeperConnection Watcher:127.0.0.1:44958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3962499 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3962499 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3962501 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3962502 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@737e0d91 name:ZooKeeperConnection Watcher:127.0.0.1:44958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3962502 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3962504 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 3962505 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 3962514 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 3962516 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 3962518 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 3962519 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 3962520 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 3962521 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 3962522 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 3962523 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3962526 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 3962529 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 3962530 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 3962530 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3962531 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000 [junit4] 2> 3962532 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37828_ojyv%2Fzy [junit4] 2> 3962532 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 3962534 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.Overseer Overseer (id=95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) starting [junit4] 2> 3962535 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 3962544 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3962545 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 3962546 INFO (OverseerCollectionConfigSetProcessor-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 3962555 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37828_ojyv%2Fzy [junit4] 2> 3962556 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37828_ojyv%2Fzy [junit4] 2> 3962556 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3962561 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3962561 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 3962570 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 3962570 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 3962571 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/cores [junit4] 2> 3962571 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3962572 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/cores/collection1 [junit4] 2> 3962572 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 3962573 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0 [junit4] 2> 3962573 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 3962573 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 3962573 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 3962577 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 3962578 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] 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:37828/ojyv/zy", [junit4] 2> "node_name":"127.0.0.1:37828_ojyv%2Fzy", [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> 3962579 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] 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:37828/ojyv/zy", [junit4] 2> "node_name":"127.0.0.1:37828_ojyv%2Fzy", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3962581 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 3962582 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 3962585 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3963578 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 3963578 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 3963578 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 3963578 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 3963579 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 3963579 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/cores/collection1' [junit4] 2> 3963579 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3963580 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3963582 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 3963588 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 3963592 WARN (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 3963593 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 3963603 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 3963608 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 3963720 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3963725 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 3963726 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 3963733 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3963735 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3963737 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3963737 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3963739 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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/J0 [junit4] 2> 3963739 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3963739 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 3963739 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:51467/solr_hdfs_home [junit4] 2> 3963739 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 3963739 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/control-001/cores/collection1], dataDir=[hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data/] [junit4] 2> 3963739 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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@5afc590c [junit4] 2> 3963740 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 3963750 WARN (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 3968754 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 3968755 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 3968763 WARN (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:51467/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 3968763 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 3968769 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 3968769 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [8388608] will allocate [1] slabs and use ~[8388608] bytes [junit4] 2> 3968769 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache [junit4] 2> 3968777 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 3968777 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 3968778 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.6044921875, noCFSRatio=0.335309214191262] [junit4] 2> 3968794 INFO (IPC Server handler 1 on 51467) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:52939 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-205d3e8a-a44f-45fe-9b4c-3004677ea215:NORMAL:127.0.0.1:53349|RBW], ReplicaUnderConstruction[[DISK]DS-a3f3ed06-bfbf-473a-b7b3-c48ee1b346fe:NORMAL:127.0.0.1:52939|FINALIZED]]} size 0 [junit4] 2> 3968797 INFO (IPC Server handler 2 on 51467) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53349 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-205d3e8a-a44f-45fe-9b4c-3004677ea215:NORMAL:127.0.0.1:53349|RBW], ReplicaUnderConstruction[[DISK]DS-a3f3ed06-bfbf-473a-b7b3-c48ee1b346fe:NORMAL:127.0.0.1:52939|FINALIZED]]} size 0 [junit4] 2> 3968799 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(BlockDirectory(HdfsDirectory@hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@23025511); maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 3968799 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 3968800 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 3968803 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3968803 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 3968803 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3968803 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3968803 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3968803 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968804 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968804 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968804 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968804 WARN (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968805 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3968806 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3968807 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3968808 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3968812 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968812 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968812 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog [junit4] 2> 3968812 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 tlogDfsReplication=2 [junit4] 2> 3968820 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3968820 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3968821 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17536903213166424] [junit4] 2> 3968828 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(BlockDirectory(HdfsDirectory@hdfs://localhost:51467/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@23025511); maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 3968828 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 3968828 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f12496c[collection1] main] [junit4] 2> 3968828 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968829 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968829 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968829 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3968829 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 3968829 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968830 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968830 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968830 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 3968830 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3968831 INFO (searcherExecutor-8922-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2f12496c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3968831 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968831 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 256 version buckets from index [junit4] 2> 3968831 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968831 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy 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 1529192325634326528 [junit4] 2> 3968831 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 0.0ms to seed version buckets with highest version 1529192325634326528 [junit4] 2> 3968831 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 3968832 INFO (coreLoadExecutor-8921-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 3968833 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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:37828/ojyv/zy collection:control_collection shard:shard1 [junit4] 2> 3968833 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968841 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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/95574519918624772-core_node1-n_0000000000 [junit4] 2> 3968841 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968843 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3968843 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3968845 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3968846 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968846 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37828/ojyv/zy/collection1/ [junit4] 2> 3968846 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3968846 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:37828/ojyv/zy/collection1/ has no replicas [junit4] 2> 3968846 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3968848 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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/95574519918624772-core_node1-n_0000000000 [junit4] 2> 3968849 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] 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:37828/ojyv/zy", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 3968851 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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:37828/ojyv/zy/collection1/ shard1 [junit4] 2> 3968951 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3968953 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:37828/ojyv/zy/collection1/ and leader is http://127.0.0.1:37828/ojyv/zy/collection1/ [junit4] 2> 3968953 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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:37828/ojyv/zy [junit4] 2> 3968953 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3968953 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 3968953 INFO (coreZkRegister-8915-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:37828_ojyv%2Fzy 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> 3968954 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] 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:37828/ojyv/zy", [junit4] 2> "node_name":"127.0.0.1:37828_ojyv%2Fzy", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 3968955 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:37828/ojyv/zy", [junit4] 2> "node_name":"127.0.0.1:37828_ojyv%2Fzy", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3969057 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3969075 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3969078 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3969078 INFO (zkCallback-4151-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b331c15 name:ZooKeeperConnection Watcher:127.0.0.1:44958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3969078 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3969078 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3969078 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3969079 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3969080 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3969179 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001/cores/collection1 [junit4] 2> 3969180 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001 [junit4] 2> 3969180 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 3969182 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a1340d2{/ojyv/zy,null,AVAILABLE} [junit4] 2> 3969183 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6eb90e98{HTTP/1.1,[http/1.1]}{127.0.0.1:59446} [junit4] 2> 3969184 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.e.j.s.Server Started @3972273ms [junit4] 2> 3969184 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:51467/hdfs__localhost_51467__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/ojyv/zy, hostPort=59446, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001/cores} [junit4] 2> 3969184 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 3969184 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001' [junit4] 2> 3969184 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3969185 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3969185 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3969185 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3969187 INFO (zkCallback-4152-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@605b366d name:ZooKeeperConnection Watcher:127.0.0.1:44958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3969187 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3969187 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3969188 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 3969188 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001/solr.xml [junit4] 2> 3969193 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001/cores [junit4] 2> 3969193 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.CoreContainer New CoreContainer 477391333 [junit4] 2> 3969193 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001] [junit4] 2> 3969193 WARN (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001/lib [junit4] 2> 3969194 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] 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> 3969195 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3969195 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3969195 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3969195 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44958/solr [junit4] 2> 3969195 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3969195 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3969196 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3969197 INFO (zkCallback-4155-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2f272e8d name:ZooKeeperConnection Watcher:127.0.0.1:44958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3969198 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3969198 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3969199 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3969200 INFO (zkCallback-4156-thread-1-processing-n:127.0.0.1:59446_ojyv%2Fzy) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6af3ee8a name:ZooKeeperConnection Watcher:127.0.0.1:44958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3969201 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3969207 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3969208 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 3969209 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3969210 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95574519918624776-127.0.0.1:59446_ojyv%2Fzy-n_0000000001 [junit4] 2> 3969210 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000 to know if I could be the leader [junit4] 2> 3969211 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.ZkController Publish node=127.0.0.1:59446_ojyv%2Fzy as DOWN [junit4] 2> 3969213 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:59446_ojyv%2Fzy"} current state version: 4 [junit4] 2> 3969213 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:59446_ojyv%2Fzy [junit4] 2> 3969315 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3969315 INFO (zkCallback-4151-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> 3969315 INFO (zkCallback-4156-thread-1-processing-n:127.0.0.1:59446_ojyv%2Fzy) [n:127.0.0.1:59446_ojyv%2Fzy ] 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> 3970213 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59446_ojyv%2Fzy [junit4] 2> 3970214 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59446_ojyv%2Fzy [junit4] 2> 3970215 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] 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> 3970215 INFO (zkCallback-4151-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> 3970215 INFO (zkCallback-4156-thread-1-processing-n:127.0.0.1:59446_ojyv%2Fzy) [n:127.0.0.1:59446_ojyv%2Fzy ] 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> 3970215 INFO (zkCallback-4149-thread-1-processing-n:127.0.0.1:37828_ojyv%2Fzy) [n:127.0.0.1:37828_ojyv%2Fzy ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3970215 INFO (zkCallback-4151-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3970216 INFO (zkCallback-4156-thread-1-processing-n:127.0.0.1:59446_ojyv%2Fzy) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 3970224 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 3970224 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 3970225 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001/cores [junit4] 2> 3970226 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] 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> 3970226 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsUnloadDistributedZkTest_ED5B5F3CAA587275-001/shard-1-001/cores/collection1 [junit4] 2> 3970226 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 3970227 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J0 [junit4] 2> 3970227 INFO (coreLoadExecutor-8932-thread-1-processing-n:127.0.0.1:59446_ojyv%2Fzy) [n:127.0.0.1:59446_ojyv%2Fzy c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 3970227 INFO (TEST-HdfsUnloadDistributedZkTest.test-seed#[ED5B5F3CAA587275]) [n:127.0.0.1:59446_ojyv%2Fzy ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 3970227 INFO (coreLoadExecutor-8932-thread-1-processing-n:127.0.0.1:59446_ojyv%2Fzy) [n:127.0.0.1:59446_ojyv%2Fzy c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 3970229 INFO (OverseerStateUpdate-95574519918624772-127.0.0.1:37828_ojyv%2Fzy-n_0000000000) [n:127.0.0.1:37828_ojyv%2Fzy ] 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:59446/ojyv/zy", [junit4] 2> "node_name":"127.0.0.1:59446_ojyv%2Fzy", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 3970229 INFO (coreLoadExecutor-8932-thread-1-processing-n:127.0.0.1:59446_ojyv%2Fzy) [n:127.0.0.1:59446_ojyv%2Fzy c:collection1 x:collection [...truncated too long message...] .MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:198) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsUnloadDistributedZkTest.teardownClass(HdfsUnloadDistributedZkTest.java:46) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 54 more [junit4] 2> 4083308 INFO (SUITE-HdfsUnloadDistributedZkTest-seed#[ED5B5F3CAA587275]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Mar 19, 2016 1:39:20 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=FSTOrd50, range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=FSTOrd50, multiDefault=PostingsFormat(name=LuceneFixedGap), a_t=FSTOrd50, intDefault=FSTOrd50, a_i1=FSTOrd50, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_i_dv=FSTOrd50, text=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l=FSTOrd50, timestamp=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=610, maxMBSortInHeap=6.173166130986909, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=sv-SE, timezone=Atlantic/Jan_Mayen [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=2,free=369929704,total=529006592 [junit4] 2> NOTE: All tests run in this JVM: [TestIBSimilarityFactory, SmileWriterTest, BadCopyFieldTest, SolrXmlInZkTest, TestRestoreCore, TestHdfsUpdateLog, TestUniqueKeyFieldResource, SystemInfoHandlerTest, OutputWriterTest, RecoveryAfterSoftCommitTest, PreAnalyzedUpdateProcessorTest, HdfsChaosMonkeySafeLeaderTest, LeaderElectionIntegrationTest, TestAnalyzedSuggestions, AlternateDirectoryTest, TestSolrXml, TestBinaryField, QueryEqualityTest, PreAnalyzedFieldTest, TestSortByMinMaxFunction, TestBlendedInfixSuggestions, TestRemoteStreaming, DisMaxRequestHandlerTest, DistributedQueryElevationComponentTest, TestSchemaManager, UUIDFieldTest, ExternalFileFieldSortTest, TestOverriddenPrefixQueryForCustomFieldType, TestPseudoReturnFields, TestFastOutputStream, TestCopyFieldCollectionResource, DistanceUnitsTest, SharedFSAutoReplicaFailoverUtilsTest, TestSchemaResource, TestSortingResponseWriter, TestReplicaProperties, AnalyticsMergeStrategyTest, TestConfig, DataDrivenBlockJoinTest, TestMergePolicyConfig, TestJsonFacets, HdfsSyncSliceTest, TestArbitraryIndexDir, OutOfBoxZkACLAndCredentialsProvidersTest, TestStressUserVersions, CachingDirectoryFactoryTest, FullHLLTest, TestDynamicFieldResource, TestPhraseSuggestions, AtomicUpdatesTest, BasicDistributedZkTest, TestMiniSolrCloudCluster, TestDistributedSearch, TestJoin, EchoParamsTest, DistributedQueryComponentOptimizationTest, TestSolrCLIRunExample, TestExactSharedStatsCache, StandardRequestHandlerTest, TestValueSourceCache, TestMiniSolrCloudClusterSSL, IndexSchemaTest, TestDynamicLoading, ResponseHeaderTest, BlockJoinFacetRandomTest, SegmentsInfoRequestHandlerTest, CollectionTooManyReplicasTest, TestDistributedStatsComponentCardinality, ResourceLoaderTest, TlogReplayBufferedWhileIndexingTest, PingRequestHandlerTest, TestReloadDeadlock, SolrCoreTest, CurrencyFieldOpenExchangeTest, TestOmitPositions, ResponseLogComponentTest, SparseHLLTest, TestCSVLoader, TestRawResponseWriter, CdcrReplicationDistributedZkTest, TestHighFrequencyDictionaryFactory, DistributedFacetPivotSmallTest, DistributedQueueTest, SignatureUpdateProcessorFactoryTest, TestFilteredDocIdSet, TestLuceneMatchVersion, TestShardHandlerFactory, AddBlockUpdateTest, CloudMLTQParserTest, TestReplicationHandler, TestFunctionQuery, RestartWhileUpdatingTest, PrimUtilsTest, TestExclusionRuleCollectionAccess, TestRandomDVFaceting, TestBadConfig, CdcrVersionReplicationTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CollectionReloadTest, ConcurrentDeleteAndCreateCollectionTest, DeleteReplicaTest, DistribDocExpirationUpdateProcessorTest, ForceLeaderTest, MigrateRouteKeyTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, TestConfigSetsAPI, TestCryptoKeys, TestDownShardTolerantSearch, TestLeaderInitiatedRecoveryThread, TestRequestForwarding, TestShortCircuitedRequests, TestSolrCloudWithKerberosAlt, HdfsBasicDistributedZkTest, HdfsUnloadDistributedZkTest] [junit4] Completed [472/580 (1!)] on J0 in 132.40s, 1 test, 1 error <<< FAILURES! [...truncated 339 lines...] 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:526: 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: There were test failures: 580 suites (10 ignored), 2320 tests, 1 error, 32 ignored (16 assumptions) [seed: ED5B5F3CAA587275] Total time: 191 minutes 52 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