Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.0/4/
3 tests failed. FAILED: org.apache.solr.client.solrj.ConnectionReuseTest.test Error Message: We expected all communication via streaming client to use one connection! expected=266 got=262 Stack Trace: java.lang.AssertionError: We expected all communication via streaming client to use one connection! expected=266 got=262 at __randomizedtesting.SeedInfo.seed([C85C326321D2AD74:40080DB98F2EC08C]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.client.solrj.ConnectionReuseTest.test(ConnectionReuseTest.java:150) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=3677, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3677, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] at __randomizedtesting.SeedInfo.seed([C85C326321D2AD74:40080DB98F2EC08C]:0) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:57510/lfzr/wf: collection already exists: awholynewstresscollection_collection5_3 at __randomizedtesting.SeedInfo.seed([C85C326321D2AD74]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:973) FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:37023 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:37023 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.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:382) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:459) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:169) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) 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) ... 47 more Build Log: [...truncated 11286 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/init-core-data-001 [junit4] 2> 395855 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) [junit4] 2> 395855 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /lfzr/wf [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 395998 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 396068 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 396069 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 396106 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-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_52124_hdfs____u8wd28/webapp [junit4] 2> 396189 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 396561 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:52124 [junit4] 2> 397129 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 397130 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 397137 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-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_44268_datanode____b9ynkr/webapp [junit4] 2> 397253 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 397615 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44268 [junit4] 2> 397757 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 397761 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 397770 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-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_48172_datanode____.qobhw1/webapp [junit4] 2> 398054 INFO (IPC Server handler 3 on 40890) [ ] BlockStateChange BLOCK* processReport: from storage DS-6a81d2ca-cf6d-44a7-997f-13a27204d00c node DatanodeRegistration(127.0.0.1, datanodeUuid=48ab65d7-ddad-4c0b-ba88-fb828f1b444f, infoPort=44268, ipcPort=47711, storageInfo=lv=-56;cid=testClusterID;nsid=1044962293;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 398054 INFO (IPC Server handler 3 on 40890) [ ] BlockStateChange BLOCK* processReport: from storage DS-d4c26d8a-154d-4c8e-acc0-9f6eb5c7a465 node DatanodeRegistration(127.0.0.1, datanodeUuid=48ab65d7-ddad-4c0b-ba88-fb828f1b444f, infoPort=44268, ipcPort=47711, storageInfo=lv=-56;cid=testClusterID;nsid=1044962293;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 398103 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 398421 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[C85C326321D2AD74]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:48172 [junit4] 2> 398570 INFO (IPC Server handler 5 on 40890) [ ] BlockStateChange BLOCK* processReport: from storage DS-5c88031e-1186-4e6e-bc50-7fe485ae3fa1 node DatanodeRegistration(127.0.0.1, datanodeUuid=89bcf6b6-2abe-4b1a-89bf-b50a51ed68c0, infoPort=48172, ipcPort=50493, storageInfo=lv=-56;cid=testClusterID;nsid=1044962293;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 398570 INFO (IPC Server handler 5 on 40890) [ ] BlockStateChange BLOCK* processReport: from storage DS-06408f8c-b87e-4554-87b4-de921ab5014d node DatanodeRegistration(127.0.0.1, datanodeUuid=89bcf6b6-2abe-4b1a-89bf-b50a51ed68c0, infoPort=48172, ipcPort=50493, storageInfo=lv=-56;cid=testClusterID;nsid=1044962293;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 398757 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 398757 INFO (Thread-1240) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 398757 INFO (Thread-1240) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 398861 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.ZkTestServer start zk server on port:35758 [junit4] 2> 398861 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 398881 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 398889 INFO (zkCallback-289-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@47bd3db2 name:ZooKeeperConnection Watcher:127.0.0.1:35758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 398889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 398889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 398889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 398937 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 398959 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 398981 INFO (zkCallback-290-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c80bfaf name:ZooKeeperConnection Watcher:127.0.0.1:35758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 398981 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 398982 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 398982 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 398982 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 398983 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 398983 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 398984 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 398984 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 398985 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 398985 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 398986 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 398986 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 398987 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 398987 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 398987 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 398987 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 398997 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 398997 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 398998 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 398998 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 398999 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 398999 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 398999 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 398999 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 399005 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 399005 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 399006 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 399006 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 399013 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 399049 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 399073 INFO (zkCallback-291-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3aea74a3 name:ZooKeeperConnection Watcher:127.0.0.1:35758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 399073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 399073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 399073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 399074 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 399081 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 399097 INFO (zkCallback-292-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@46b5277f name:ZooKeeperConnection Watcher:127.0.0.1:35758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 399097 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 399097 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 399098 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 399098 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 399110 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 399110 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 399110 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 399111 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 399111 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 399111 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 399112 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 399112 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 399112 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 399112 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 399290 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 399290 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 399291 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 399291 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 399291 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 399291 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 399292 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 399292 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 399293 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 399293 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 399293 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 399293 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 399370 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/cores/collection1 [junit4] 2> 399374 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 399378 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3dc2cdbf{/lfzr/wf,null,AVAILABLE} [junit4] 2> 399378 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.e.j.s.ServerConnector Started ServerConnector@75be7c5{HTTP/1.1,[http/1.1]}{127.0.0.1:58402} [junit4] 2> 399378 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.e.j.s.Server Started @405270ms [junit4] 2> 399378 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:40890/hdfs__localhost_40890__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.0_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001_tempDir-002_control_data, hostContext=/lfzr/wf, hostPort=58402, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/cores} [junit4] 2> 399379 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 399379 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001' [junit4] 2> 399379 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 399379 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 399380 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 399380 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 399381 INFO (zkCallback-293-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6cbfb269 name:ZooKeeperConnection Watcher:127.0.0.1:35758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 399381 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 399381 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 399383 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 399383 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/solr.xml [junit4] 2> 399387 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/cores [junit4] 2> 399387 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.CoreContainer New CoreContainer 1238201266 [junit4] 2> 399387 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001] [junit4] 2> 399387 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/lib [junit4] 2> 399387 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] 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> 399388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 399388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 399388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 399388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35758/solr [junit4] 2> 399388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 399388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 399388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 399389 INFO (zkCallback-296-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@42bdfde4 name:ZooKeeperConnection Watcher:127.0.0.1:35758 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 399390 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 399390 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 399391 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 399392 INFO (zkCallback-297-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68606dac name:ZooKeeperConnection Watcher:127.0.0.1:35758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 399392 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 399393 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 399395 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 399396 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 399397 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 399398 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 399399 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 399399 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 399400 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 399400 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 399401 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 399401 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 399410 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 399410 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 399411 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 399411 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000 [junit4] 2> 399411 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58402_lfzr%2Fwf [junit4] 2> 399411 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 399412 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.Overseer Overseer (id=95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) starting [junit4] 2> 399413 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 399416 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 399416 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 399417 INFO (OverseerCollectionConfigSetProcessor-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 399424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58402_lfzr%2Fwf [junit4] 2> 399424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58402_lfzr%2Fwf [junit4] 2> 399429 INFO (zkCallback-297-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf ] 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> 399429 INFO (zkCallback-297-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 399432 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 399433 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 399433 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 399434 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/cores [junit4] 2> 399435 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] 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> 399436 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/cores/collection1 [junit4] 2> 399436 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 399436 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 399436 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 399438 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 399439 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1 [junit4] 2> 399439 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 399439 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] 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:58402/lfzr/wf", [junit4] 2> "node_name":"127.0.0.1:58402_lfzr%2Fwf", [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> 399439 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] 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:58402/lfzr/wf", [junit4] 2> "node_name":"127.0.0.1:58402_lfzr%2Fwf", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 399439 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 399440 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 399441 INFO (zkCallback-297-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf ] 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> 400441 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 400441 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 400441 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 400441 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 400442 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 400442 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/cores/collection1' [junit4] 2> 400442 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 400442 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 400443 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 400451 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 400455 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 400463 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 400467 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 400647 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 400651 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 400652 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 400664 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 400665 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 400667 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 400667 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 400668 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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-6.0/solr/build/solr-core/test/J1 [junit4] 2> 400668 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 400668 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 400668 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:40890/solr_hdfs_home [junit4] 2> 400668 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 400668 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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-6.0/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_C85C326321D2AD74-001/control-001/cores/collection1], dataDir=[hdfs://localhost:40890/solr_hdfs_home/control_collection/core_node1/data/] [junit4] 2> 400668 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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@1758f18 [junit4] 2> 400669 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:40890/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 400699 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:40890/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 400704 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:40890/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 400712 WARN (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:40890/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 400712 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:40890/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 400731 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:40890/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 400731 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=33.728515625, floorSegmentMB=1.91796875, forceMergeDeletesPctAllowed=20.174584174264737, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.718067646397911 [junit4] 2> 400823 INFO (IPC Server handler 0 on 40890) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37053 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-d4c26d8a-154d-4c8e-acc0-9f6eb5c7a465:NORMAL:127.0.0.1:34820|RBW], ReplicaUnderConstruction[[DISK]DS-5c88031e-1186-4e6e-bc50-7fe485ae3fa1:NORMAL:127.0.0.1:37053|RBW]]} size 0 [junit4] 2> 400833 INFO (IPC Server handler 2 on 40890) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34820 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5c88031e-1186-4e6e-bc50-7fe485ae3fa1:NORMAL:127.0.0.1:37053|RBW], ReplicaUnderConstruction[[DISK]DS-6a81d2ca-cf6d-44a7-997f-13a27204d00c:NORMAL:127.0.0.1:34820|FINALIZED]]} size 0 [junit4] 2> 400835 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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(HdfsDirectory@hdfs://localhost:40890/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78363a06; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 400835 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 400840 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 400840 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 400840 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 400840 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 400840 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 400840 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400841 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400841 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400841 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400841 WARN (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400850 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 400851 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 400857 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 400858 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 400865 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400865 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400869 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400869 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 400877 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58402_lfzr%2Fwf 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:40890/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 400909 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 400909 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 400934 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=3.9990234375, floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=13.237570672122715, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 401250 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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(HdfsDirectory@hdfs://localhost:40890/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78363a06; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 401250 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 401250 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7095e319[collection1] main] [junit4] 2> 401250 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401257 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401257 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401261 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 401261 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 401261 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401265 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401265 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401265 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 401265 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 401272 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401272 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401272 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401272 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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 1530553776387129344 [junit4] 2> 401272 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf 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 1530553776387129344 [junit4] 2> 401272 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 401272 INFO (searcherExecutor-795-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7095e319[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 401273 INFO (coreLoadExecutor-793-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 401274 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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:58402/lfzr/wf collection:control_collection shard:shard1 [junit4] 2> 401274 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401281 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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/95659610859438086-core_node1-n_0000000000 [junit4] 2> 401281 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401282 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] 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> 401283 INFO (zkCallback-297-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf ] 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> 401284 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 401284 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401284 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58402/lfzr/wf/collection1/ [junit4] 2> 401284 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 401284 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58402/lfzr/wf/collection1/ has no replicas [junit4] 2> 401289 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 401290 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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/95659610859438086-core_node1-n_0000000000 [junit4] 2> 401291 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] 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:58402/lfzr/wf", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 401297 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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:58402/lfzr/wf/collection1/ shard1 [junit4] 2> 401399 INFO (zkCallback-297-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf) [n:127.0.0.1:58402_lfzr%2Fwf ] 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> 401400 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58402/lfzr/wf/collection1/ and leader is http://127.0.0.1:58402/lfzr/wf/collection1/ [junit4] 2> 401400 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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:58402/lfzr/wf [junit4] 2> 401401 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 401401 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 401401 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf 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> 401401 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] 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:58402/lfzr/wf", [junit4] 2> "node_name":"127.0.0.1:58402_lfzr%2Fwf", [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> 401402 INFO (OverseerStateUpdate-95659610859438086-127.0.0.1:58402_lfzr%2Fwf-n_0000000000) [n:127.0.0.1:58402_lfzr%2Fwf ] 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:58402/lfzr/wf", [junit4] 2> "node_name":"127.0.0.1:58402_lfzr%2Fwf", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 401403 INFO (coreZkRegister-788-thread-1-processing-n:127.0.0.1:58402_lfzr%2Fwf x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58402_lfzr%2Fwf c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 401440 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 401449 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 401455 INFO (zkCallback-299-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3525a935 name:ZooKeeperConnection Watcher:127.0.0.1:35758/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 401455 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 401455 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.c.SolrZkClient Using [...truncated too long message...] e1 x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.client.solrj.ConnectionReuseTest_C85C326321D2AD74-001/shard-1-001/cores/collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.client.solrj.ConnectionReuseTest_C85C326321D2AD74-001/shard-1-001/cores/collection1/data;done=false>>] [junit4] 2> 4811699 INFO (coreCloseExecutor-7502-thread-1) [n:127.0.0.1:42290_ c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.client.solrj.ConnectionReuseTest_C85C326321D2AD74-001/shard-1-001/cores/collection1/data [junit4] 2> 4811699 INFO (TEST-ConnectionReuseTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.Overseer Overseer (id=95659893199601673-127.0.0.1:42290_-n_0000000001) closing [junit4] 2> 4811700 INFO (OverseerStateUpdate-95659893199601673-127.0.0.1:42290_-n_0000000001) [n:127.0.0.1:42290_ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42290_ [junit4] 2> 4811701 INFO (zkCallback-2710-thread-2-processing-n:127.0.0.1:42290_) [n:127.0.0.1:42290_ ] 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> 4811701 INFO (zkCallback-2710-thread-3-processing-n:127.0.0.1:42290_) [n:127.0.0.1:42290_ ] 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> 4813219 WARN (zkCallback-2710-thread-2-processing-n:127.0.0.1:42290_) [n:127.0.0.1:42290_ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 4813219 WARN (zkCallback-2710-thread-3-processing-n:127.0.0.1:42290_) [n:127.0.0.1:42290_ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /clusterstate.json] [junit4] 2> 4813222 INFO (TEST-ConnectionReuseTest.test-seed#[C85C326321D2AD74]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@29b1e8d5{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 4813223 INFO (TEST-ConnectionReuseTest.test-seed#[C85C326321D2AD74]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2369557b{/,null,UNAVAILABLE} [junit4] 2> 4813225 INFO (TEST-ConnectionReuseTest.test-seed#[C85C326321D2AD74]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54979 54979 [junit4] 2> 4813412 INFO (Thread-6690) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54979 54979 [junit4] 2> 4813412 WARN (Thread-6690) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 3 /solr/aliases.json [junit4] 2> 2 /solr/security.json [junit4] 2> 2 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 3 /solr/clusterstate.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 3 /solr/live_nodes [junit4] 2> 3 /solr/collections [junit4] 2> 2 /solr/overseer/queue [junit4] 2> 2 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ConnectionReuseTest -Dtests.method=test -Dtests.seed=C85C326321D2AD74 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=uk-UA -Dtests.timezone=America/Rankin_Inlet -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 106s J2 | ConnectionReuseTest.test <<< [junit4] > Throwable #1: java.lang.AssertionError: We expected all communication via streaming client to use one connection! expected=266 got=262 [junit4] > at __randomizedtesting.SeedInfo.seed([C85C326321D2AD74:40080DB98F2EC08C]:0) [junit4] > at org.apache.solr.client.solrj.ConnectionReuseTest.test(ConnectionReuseTest.java:150) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 4813427 INFO (SUITE-ConnectionReuseTest-seed#[C85C326321D2AD74]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.client.solrj.ConnectionReuseTest_C85C326321D2AD74-001 [junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=17884, maxDocsPerChunk=166, blockSize=9), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=17884, blockSize=9)), sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=uk-UA, timezone=America/Rankin_Inlet [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=2,free=95312208,total=461897728 [junit4] 2> NOTE: All tests run in this JVM: [HdfsLockFactoryTest, SolrCoreCheckLockOnStartupTest, HdfsRestartWhileUpdatingTest, HdfsChaosMonkeyNothingIsSafeTest, BinaryUpdateRequestHandlerTest, OverseerTest, FieldMutatingUpdateProcessorTest, TestSolrConfigHandlerCloud, TestIndexSearcher, UnloadDistributedZkTest, TestComplexPhraseQParserPlugin, ExitableDirectoryReaderTest, MergeStrategyTest, CdcrUpdateLogTest, TestPerFieldSimilarityWithDefaultOverride, DeleteStatusTest, TestSolrCLIRunExample, SuggesterTest, DistributedQueryComponentCustomSortTest, TestXIncludeConfig, TestSchemaManager, TestReloadDeadlock, UpdateParamsTest, TestSerializedLuceneMatchVersion, TestFilteredDocIdSet, SuggesterTSTTest, AutoCommitTest, SolrCloudExampleTest, TestExceedMaxTermLength, DocValuesMultiTest, FacetPivotSmallTest, TestReloadAndDeleteDocs, TestUniqueKeyFieldResource, TestJmxMonitoredMap, BadIndexSchemaTest, BigEndianAscendingWordSerializerTest, TestSolrQueryParser, AddSchemaFieldsUpdateProcessorFactoryTest, PluginInfoTest, TestPostingsSolrHighlighter, TestDistribIDF, RegexBoostProcessorTest, BadCopyFieldTest, TestUtils, DistribJoinFromCollectionTest, TestRequestStatusCollectionAPI, TestAuthorizationFramework, BlockDirectoryTest, OverseerTaskQueueTest, XmlUpdateRequestHandlerTest, NoCacheHeaderTest, CurrencyFieldXmlFileTest, TestSha256AuthenticationProvider, BlockJoinFacetRandomTest, TestPerFieldSimilarity, TestSuggestSpellingConverter, CoreAdminRequestStatusTest, SimpleFacetsTest, TestSearchPerf, CdcrRequestHandlerTest, PrimitiveFieldTypeTest, TestLRUStatsCache, CollectionsAPISolrJTest, TestConfigSetsAPIZkFailure, DistanceUnitsTest, TestCoreDiscovery, TestSolrCloudWithKerberosAlt, TestDFISimilarityFactory, TestConfigSets, TestSweetSpotSimilarityFactory, TestGroupingSearch, DocValuesMissingTest, TestRebalanceLeaders, TestMaxScoreQueryParser, TestFreeTextSuggestions, DebugComponentTest, DirectSolrConnectionTest, TestScoreJoinQPNoScore, VMParamsZkACLAndCredentialsProvidersTest, CollectionStateFormat2Test, TestDownShardTolerantSearch, LeaderInitiatedRecoveryOnCommitTest, TestPivotHelperCode, TestSchemaResource, TestExtendedDismaxParser, TestSortingResponseWriter, SuggestComponentTest, TestPHPSerializedResponseWriter, SliceStateTest, RecoveryZkTest, ForceLeaderTest, TestReqParamsAPI, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, TestReplicationHandler, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestTolerantSearch, ConnectionReuseTest] [junit4] Completed [437/579 (3!)] on J2 in 106.19s, 1 test, 1 failure <<< FAILURES! [...truncated 473 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/common-build.xml:527: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/lucene/common-build.xml:1014: There were test failures: 579 suites (10 ignored), 2309 tests, 2 errors, 1 failure, 31 ignored (16 assumptions) [seed: C85C326321D2AD74] Total time: 290 minutes 48 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org