You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/08/29 17:21:09 UTC
[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_102) - Build #
17715 - Still Unstable!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17715/
Java: 32bit/jdk1.8.0_102 -client -XX:+UseG1GC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 3 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 3 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
at __randomizedtesting.SeedInfo.seed([863A93BEA0CEDBAE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:257)
at sun.reflect.GeneratedMethodAccessor17.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:834)
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.core.TestDynamicLoading.testDynamicLoading
Error Message:
Could not get expected value 'X val' for path 'x' full output: { "responseHeader":{ "status":0, "QTime":0}, "params":{"wt":"json"}, "context":{ "webapp":"/mf_/w", "path":"/test1", "httpMethod":"GET"}, "class":"org.apache.solr.core.BlobStoreTestRequestHandler", "x":null}, from server: null
Stack Trace:
java.lang.AssertionError: Could not get expected value 'X val' for path 'x' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"params":{"wt":"json"},
"context":{
"webapp":"/mf_/w",
"path":"/test1",
"httpMethod":"GET"},
"class":"org.apache.solr.core.BlobStoreTestRequestHandler",
"x":null}, from server: null
at __randomizedtesting.SeedInfo.seed([863A93BEA0CEDBAE:5E77BEE957137E0E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:535)
at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:232)
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:985)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
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.security.BasicAuthIntegrationTest.testBasics
Error Message:
IOException occured when talking to server at: http://127.0.0.1:39300/solr/testSolrCloudCollection_shard1_replica1
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: IOException occured when talking to server at: http://127.0.0.1:39300/solr/testSolrCloudCollection_shard1_replica1
at __randomizedtesting.SeedInfo.seed([863A93BEA0CEDBAE:BBE23D92982085DE]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:755)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1161)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1050)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:992)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.security.BasicAuthIntegrationTest.doExtraTests(BasicAuthIntegrationTest.java:193)
at org.apache.solr.cloud.TestMiniSolrCloudClusterBase.testCollectionCreateSearchDelete(TestMiniSolrCloudClusterBase.java:196)
at org.apache.solr.cloud.TestMiniSolrCloudClusterBase.testBasics(TestMiniSolrCloudClusterBase.java:79)
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 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: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:39300/solr/testSolrCloudCollection_shard1_replica1
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:622)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:415)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:367)
at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:730)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
... 1 more
Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:39300 failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
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.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167)
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.execchain.MainClientExec.execute(MainClientExec.java:271)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:513)
... 10 more
Build Log:
[...truncated 10975 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/init-core-data-001
[junit4] 2> 383519 INFO (SUITE-TestDynamicLoading-seed#[863A93BEA0CEDBAE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 383519 INFO (SUITE-TestDynamicLoading-seed#[863A93BEA0CEDBAE]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /mf_/w
[junit4] 2> 383521 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 383522 INFO (Thread-967) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 383522 INFO (Thread-967) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 383622 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.ZkTestServer start zk server on port:43659
[junit4] 2> 383622 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 383622 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 383625 INFO (zkCallback-662-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@619aea name:ZooKeeperConnection Watcher:127.0.0.1:43659 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 383625 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 383626 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 383626 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 383629 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x156d73199660000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 383629 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 383630 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 383631 INFO (zkCallback-663-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b45509 name:ZooKeeperConnection Watcher:127.0.0.1:43659/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 383631 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 383632 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 383632 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 383634 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 383635 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 383636 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 383637 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 383638 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 383639 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 383640 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 383641 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 383641 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 383643 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 383643 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 383644 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 383644 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 383646 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 383646 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 383647 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 383647 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 383648 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 383648 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 383649 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 383650 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 383651 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 383651 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 383652 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 383652 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 383739 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1
[junit4] 2> 383740 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 383741 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@10f5626{/mf_/w,null,AVAILABLE}
[junit4] 2> 383742 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@102870c{HTTP/1.1,[http/1.1]}{127.0.0.1:44312}
[junit4] 2> 383742 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.Server Started @385438ms
[junit4] 2> 383742 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/tempDir-001/control/data, hostContext=/mf_/w, hostPort=44312, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores}
[junit4] 2> 383743 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 383743 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001'
[junit4] 2> 383743 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 383743 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 383743 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 383744 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 383745 INFO (zkCallback-664-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@153e70f name:ZooKeeperConnection Watcher:127.0.0.1:43659/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 383745 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 383746 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 383747 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 383747 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/solr.xml
[junit4] 2> 383756 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores
[junit4] 2> 383756 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.CoreContainer New CoreContainer 19810478
[junit4] 2> 383756 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001]
[junit4] 2> 383756 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/lib
[junit4] 2> 383756 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] 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,
[junit4] 2> 383759 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 383759 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 383759 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 383759 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43659/solr
[junit4] 2> 383759 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 383759 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 383759 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 383761 INFO (zkCallback-667-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f86947 name:ZooKeeperConnection Watcher:127.0.0.1:43659 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 383761 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 383762 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 383763 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$12/9730706@143a4d
[junit4] 2> 383764 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 383765 INFO (zkCallback-668-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@18c0686 name:ZooKeeperConnection Watcher:127.0.0.1:43659/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 383765 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 383767 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 383768 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 383771 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 383772 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 383774 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 383775 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 383776 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 383778 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 383779 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 383780 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 383781 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 383781 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 383783 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 383783 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 383784 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 383785 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000
[junit4] 2> 383785 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44312_mf_%2Fw
[junit4] 2> 383785 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 383786 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.Overseer Overseer (id=96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) starting
[junit4] 2> 383787 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 383792 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 383792 INFO (OverseerCollectionConfigSetProcessor-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 383792 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 383793 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44312_mf_%2Fw
[junit4] 2> 383793 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44312_mf_%2Fw
[junit4] 2> 383794 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 383794 INFO (zkCallback-668-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw ] 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> 383794 INFO (zkCallback-668-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 383846 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 383847 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 383847 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores
[junit4] 2> 383848 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] 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> 383848 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.CorePropertiesLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1
[junit4] 2> 383848 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 383849 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 383849 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
[junit4] 2> 383849 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 383849 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 383850 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible
[junit4] 2> 383851 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] 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:44312/mf_/w",
[junit4] 2> "node_name":"127.0.0.1:44312_mf_%2Fw",
[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> 383851 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] 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:44312/mf_/w",
[junit4] 2> "node_name":"127.0.0.1:44312_mf_%2Fw",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 383851 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
[junit4] 2> 383851 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 383851 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 383852 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 383853 INFO (zkCallback-668-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw ] 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> 384852 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 384852 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 384853 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 384853 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
[junit4] 2> 384853 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 384853 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1'
[junit4] 2> 384854 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 384854 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 384855 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 384864 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 384880 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 384892 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 384905 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 385014 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 385021 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 385022 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 385030 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 385032 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 385034 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 385034 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 385036 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
[junit4] 2> 385036 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 385036 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 385036 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1/data/]
[junit4] 2> 385036 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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@186bb06
[junit4] 2> 385037 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1/data/snapshot_metadata
[junit4] 2> 385037 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 385037 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1/data
[junit4] 2> 385038 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1/data/index/
[junit4] 2> 385038 WARN (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 385038 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1/data/index
[junit4] 2> 385038 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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=38, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=72.1533203125, floorSegmentMB=0.2275390625, forceMergeDeletesPctAllowed=7.167976748144715, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 385039 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ea5b92 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fd85ac),segFN=segments_1,generation=1}
[junit4] 2> 385039 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 385039 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44312_mf_%2Fw 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 /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1/data/
[junit4] 2> 385039 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 385044 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 385044 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 385044 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 385044 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 385045 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 385045 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385045 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385045 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385045 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385046 WARN (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385047 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 385048 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 385050 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 385051 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 385061 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
[junit4] 2> 385062 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385062 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 385062 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 385063 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 385063 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 385063 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 385064 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1ea5b92 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fd85ac),segFN=segments_1,generation=1}
[junit4] 2> 385064 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 385064 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@72fa32[collection1] main]
[junit4] 2> 385064 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385065 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385065 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385065 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 385065 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 385065 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385065 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385066 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385066 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 385066 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 385067 INFO (searcherExecutor-1732-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72fa32[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 385067 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385067 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 385067 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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> 385067 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw 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 1544016802611200000
[junit4] 2> 385069 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1544016802611200000
[junit4] 2> 385069 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 385069 INFO (coreLoadExecutor-1731-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 385070 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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:44312/mf_/w collection:control_collection shard:shard1
[junit4] 2> 385070 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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> 385072 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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/96501050062012420-core_node1-n_0000000000
[junit4] 2> 385072 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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=10000
[junit4] 2> 385074 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 385074 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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> 385074 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44312/mf_/w/collection1/
[junit4] 2> 385074 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 385074 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:44312/mf_/w/collection1/ has no replicas
[junit4] 2> 385074 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] 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> 385075 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 385076 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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/96501050062012420-core_node1-n_0000000000
[junit4] 2> 385078 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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:44312/mf_/w/collection1/ shard1
[junit4] 2> 385078 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] 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:44312/mf_/w",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 385180 INFO (zkCallback-668-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw ] 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> 385229 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:44312/mf_/w/collection1/ and leader is http://127.0.0.1:44312/mf_/w/collection1/
[junit4] 2> 385229 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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:44312/mf_/w
[junit4] 2> 385229 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 385229 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 385229 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw 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> 385230 INFO (coreZkRegister-1724-thread-1-processing-n:127.0.0.1:44312_mf_%2Fw x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44312_mf_%2Fw c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection control_collection
[junit4] 2> 385231 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] 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:44312/mf_/w",
[junit4] 2> "node_name":"127.0.0.1:44312_mf_%2Fw",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 385231 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] 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:44312/mf_/w",
[junit4] 2> "node_name":"127.0.0.1:44312_mf_%2Fw",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 385333 INFO (zkCallback-668-thread-2-processing-n:127.0.0.1:44312_mf_%2Fw) [n:127.0.0.1:44312_mf_%2Fw ] 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> 385352 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 385353 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 385353 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@50b0aa name:ZooKeeperConnection Watcher:127.0.0.1:43659/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 385353 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 385354 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 385354 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 385354 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 385355 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 385355 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 385356 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 385356 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 385356 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 385357 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1c571b4 name:ZooKeeperConnection Watcher:127.0.0.1:43659/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 385357 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 385357 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 385359 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 3
[junit4] 2> 385360 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 385451 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001/cores/collection1
[junit4] 2> 385452 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001
[junit4] 2> 385453 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 385455 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@103ce16{/mf_/w,null,AVAILABLE}
[junit4] 2> 385456 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1f25d40{HTTP/1.1,[http/1.1]}{127.0.0.1:44599}
[junit4] 2> 385457 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.Server Started @387152ms
[junit4] 2> 385457 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/mf_/w, hostPort=44599, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001/cores}
[junit4] 2> 385457 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 385458 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001'
[junit4] 2> 385458 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 385458 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 385458 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 385459 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 385460 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@18b23b name:ZooKeeperConnection Watcher:127.0.0.1:43659/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 385461 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 385461 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 385463 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 385463 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001/solr.xml
[junit4] 2> 385463 INFO (OverseerStateUpdate-96501050062012420-127.0.0.1:44312_mf_%2Fw-n_0000000000) [n:127.0.0.1:44312_mf_%2Fw ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
[junit4] 2> 385476 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001/cores
[junit4] 2> 385476 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.CoreContainer New CoreContainer 31124949
[junit4] 2> 385476 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001]
[junit4] 2> 385476 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_863A93BEA0CEDBAE-001/shard-1-001/lib
[junit4] 2> 385476 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] 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,
[junit4] 2> 385480 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 385480 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 385480 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 385480 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43659/solr
[junit4] 2> 385480 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 385480 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 385482 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 385483 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@125ed71 name:ZooKeeperConnection Watcher:127.0.0.1:43659 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 385484 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 385484 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 385486 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[863A93BEA0CEDBAE]) [n:127.0.0.1:44599_mf_%2Fw ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$12/9730706@1935884
[junit4] 2> 385486 INFO (TEST-TestDynamicLoading.testDynamicLoadi
[...truncated too long message...]
.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 3 directories currently being tracked
[junit4] 2> 1252462 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data/index.20160829200036033 [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data/index.20160829200036033;done=false>>]
[junit4] 2> 1252462 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1252463 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data/index.20160829200036033
[junit4] 2> 1252463 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data;done=false>>]
[junit4] 2> 1252463 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1252463 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data
[junit4] 2> 1252463 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data/snapshot_metadata [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data/snapshot_metadata;done=false>>]
[junit4] 2> 1252463 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1252463 INFO (coreCloseExecutor-8679-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_863A93BEA0CEDBAE-001/solr-instance-030/./collection1/data/snapshot_metadata
[junit4] 2> 1252464 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[863A93BEA0CEDBAE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@12ee17a{/solr,null,UNAVAILABLE}
[junit4] 2> 1252466 INFO (SUITE-TestReplicationHandler-seed#[863A93BEA0CEDBAE]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> ObjectTracker found 3 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:384)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:384)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:384)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {name=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=Memory doPackFST= true), newname=PostingsFormat(name=Memory doPackFST= true)}, docValues:{}, maxPointsInLeafNode=1918, maxMBSortInHeap=7.1301317901112835, sim=ClassicSimilarity, locale=ar-SA, timezone=Europe/Moscow
[junit4] 2> NOTE: Linux 4.4.0-31-generic i386/Oracle Corporation 1.8.0_102 (32-bit)/cpus=12,threads=1,free=240430096,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomRequestDistribution, OverseerCollectionConfigSetProcessorTest, HardAutoCommitTest, TestSuggestSpellingConverter, BlobRepositoryCloudTest, BitVectorTest, TestXmlQParserPlugin, TestRecovery, TestOrdValues, DateMathParserTest, TestFuzzyAnalyzedSuggestions, TestSortingResponseWriter, TestLegacyFieldCache, TestFreeTextSuggestions, BlockJoinFacetDistribTest, BadIndexSchemaTest, TestShortCircuitedRequests, DirectUpdateHandlerTest, TestMiniSolrCloudClusterSSL, HdfsSyncSliceTest, TestSolrConfigHandlerConcurrent, TestFieldCacheReopen, TestSolr4Spatial2, TestZkChroot, FieldAnalysisRequestHandlerTest, TestConfigSets, TestStressRecovery, TestManagedSynonymFilterFactory, TestSolrQueryParserResource, SampleTest, TestSolrDynamicMBean, TestReRankQParserPlugin, TestExtendedDismaxParser, TestXIncludeConfig, TestDistributedStatsComponentCardinality, CustomCollectionTest, TestExceedMaxTermLength, TestMiniSolrCloudCluster, DirectSolrConnectionTest, AssignTest, TestComplexPhraseQParserPlugin, DebugComponentTest, CircularListTest, TestJsonRequest, TestAddFieldRealTimeGet, HdfsUnloadDistributedZkTest, DeleteNodeTest, TestLeaderInitiatedRecoveryThread, HdfsChaosMonkeySafeLeaderTest, TestWriterPerf, TestSha256AuthenticationProvider, TestCollationFieldDocValues, TestFieldResource, FieldMutatingUpdateProcessorTest, SliceStateTest, PrimitiveFieldTypeTest, HighlighterConfigTest, UpdateParamsTest, TestSearchPerf, AutoCommitTest, TestRestoreCore, RequestLoggingTest, DirectUpdateHandlerOptimizeTest, TestDynamicFieldCollectionResource, EnumFieldTest, XmlUpdateRequestHandlerTest, TestCharFilters, SolrTestCaseJ4Test, RequestHandlersTest, TestTolerantSearch, SpatialHeatmapFacetsTest, TestCloudManagedSchema, TestConfigSetsAPI, TestUtils, TestQueryWrapperFilter, BaseCdcrDistributedZkTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestExclusionRuleCollectionAccess, ForceLeaderTest, TestCryptoKeys, UnloadDistributedZkTest, TestConfigOverlay, RecoveryAfterSoftCommitTest, DeleteStatusTest, TestInfoStreamLogging, OverseerStatusTest, TestClassicSimilarityFactory, ChangedSchemaMergeTest, TestInitQParser, TestReloadAndDeleteDocs, SSLMigrationTest, TermVectorComponentDistributedTest, TestJettySolrRunner, TestTestInjection, TestStressLiveNodes, ShowFileRequestHandlerTest, CollectionReloadTest, TestStressReorder, HdfsRecoverLeaseTest, BinaryUpdateRequestHandlerTest, CdcrReplicationHandlerTest, SyncSliceTest, OverseerTest, RecoveryZkTest, TestReplicationHandler]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.seed=863A93BEA0CEDBAE -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-SA -Dtests.timezone=Europe/Moscow -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | TestReplicationHandler (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 3 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
[junit4] > at __randomizedtesting.SeedInfo.seed([863A93BEA0CEDBAE]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:257)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [332/632 (3!)] on J2 in 76.87s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 53419 lines...]
[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_102) - Build #
17716 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17716/
Java: 32bit/jdk1.8.0_102 -client -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasics
Error Message:
IOException occured when talking to server at: http://127.0.0.1:37379/solr/testSolrCloudCollection_shard1_replica1
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: IOException occured when talking to server at: http://127.0.0.1:37379/solr/testSolrCloudCollection_shard1_replica1
at __randomizedtesting.SeedInfo.seed([D92FD8A0EF60BDC:304A53A6361855AC]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:755)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1161)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1050)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:992)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.security.BasicAuthIntegrationTest.doExtraTests(BasicAuthIntegrationTest.java:193)
at org.apache.solr.cloud.TestMiniSolrCloudClusterBase.testCollectionCreateSearchDelete(TestMiniSolrCloudClusterBase.java:196)
at org.apache.solr.cloud.TestMiniSolrCloudClusterBase.testBasics(TestMiniSolrCloudClusterBase.java:79)
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 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: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:37379/solr/testSolrCloudCollection_shard1_replica1
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:622)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:415)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:367)
at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:730)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
... 1 more
Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:37379 failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
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.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167)
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.execchain.MainClientExec.execute(MainClientExec.java:271)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:513)
... 10 more
Build Log:
[...truncated 12456 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest
[junit4] 2> 1988422 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1988422 INFO (Thread-4078) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1988422 INFO (Thread-4078) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1988522 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.ZkTestServer start zk server on port:32965
[junit4] 2> 1988523 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988523 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988555 INFO (zkCallback-26600-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a807 name:ZooKeeperConnection Watcher:127.0.0.1:32965 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988555 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988556 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988556 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 1988577 INFO (jetty-launcher-26599-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1988577 INFO (jetty-launcher-26599-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1988577 INFO (jetty-launcher-26599-thread-3) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1988578 INFO (jetty-launcher-26599-thread-4) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1988578 INFO (jetty-launcher-26599-thread-5) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@784a99{/solr,null,AVAILABLE}
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c49855{/solr,null,AVAILABLE}
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e32466{/solr,null,AVAILABLE}
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@18c715d{/solr,null,AVAILABLE}
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@1f7f469{HTTP/1.1,[http/1.1]}{127.0.0.1:40947}
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-3) [ ] o.e.j.s.ServerConnector Started ServerConnector@f764a7{HTTP/1.1,[http/1.1]}{127.0.0.1:44636}
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-1) [ ] o.e.j.s.Server Started @1990066ms
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-3) [ ] o.e.j.s.Server Started @1990066ms
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40947}
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44636}
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 1988580 INFO (jetty-launcher-26599-thread-5) [ ] o.e.j.s.ServerConnector Started ServerConnector@585e0b{HTTP/1.1,[http/1.1]}{127.0.0.1:37379}
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node1'
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-5) [ ] o.e.j.s.Server Started @1990067ms
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1de746f{/solr,null,AVAILABLE}
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@12733bf{HTTP/1.1,[http/1.1]}{127.0.0.1:44583}
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-2) [ ] o.e.j.s.Server Started @1990067ms
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node3'
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44583}
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-4) [ ] o.e.j.s.ServerConnector Started ServerConnector@180b301{HTTP/1.1,[http/1.1]}{127.0.0.1:41996}
[junit4] 2> 1988581 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37379}
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-4) [ ] o.e.j.s.Server Started @1990068ms
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node5'
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node2'
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1988583 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988583 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41996}
[junit4] 2> 1988582 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1988583 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988583 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988583 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 1988583 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988583 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node4'
[junit4] 2> 1988583 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1988584 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1988584 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988584 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988584 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988584 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988585 INFO (zkCallback-26602-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d94e39 name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988585 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988586 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988587 INFO (zkCallback-26601-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9e2365 name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988587 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988587 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988587 INFO (zkCallback-26603-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@114a1bd name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988587 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988587 INFO (zkCallback-26605-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e9659f name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1988588 INFO (zkCallback-26604-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1571d66 name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1988588 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1988599 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node2/.
[junit4] 2> 1988599 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x156d7cd9a8a0004, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1988599 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node4/.
[junit4] 2> 1988600 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 16482616
[junit4] 2> 1988600 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.CoreContainer New CoreContainer 26822258
[junit4] 2> 1988600 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node2]
[junit4] 2> 1988600 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node4]
[junit4] 2> 1988600 WARN (jetty-launcher-26599-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node2/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node2/lib
[junit4] 2> 1988600 WARN (jetty-launcher-26599-thread-4) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node4/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node4/lib
[junit4] 2> 1988601 INFO (jetty-launcher-26599-thread-2) [ ] 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,
[junit4] 2> 1988602 INFO (jetty-launcher-26599-thread-4) [ ] 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,
[junit4] 2> 1988602 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node5/.
[junit4] 2> 1988602 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node1/.
[junit4] 2> 1988602 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.CoreContainer New CoreContainer 16388869
[junit4] 2> 1988602 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 10165439
[junit4] 2> 1988602 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node5]
[junit4] 2> 1988602 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node1]
[junit4] 2> 1988602 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x156d7cd9a8a0002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1988603 WARN (jetty-launcher-26599-thread-5) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node5/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node5/lib
[junit4] 2> 1988603 WARN (jetty-launcher-26599-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node1/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node1/lib
[junit4] 2> 1988603 INFO (jetty-launcher-26599-thread-5) [ ] 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,
[junit4] 2> 1988603 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node3/.
[junit4] 2> 1988603 INFO (jetty-launcher-26599-thread-1) [ ] 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,
[junit4] 2> 1988603 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.CoreContainer New CoreContainer 29035716
[junit4] 2> 1988603 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node3]
[junit4] 2> 1988603 WARN (jetty-launcher-26599-thread-3) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node3/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node3/lib
[junit4] 2> 1988604 INFO (jetty-launcher-26599-thread-3) [ ] 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,
[junit4] 2> 1988604 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1988604 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1988604 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32965/solr
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32965/solr
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1988605 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32965/solr
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32965/solr
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988606 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32965/solr
[junit4] 2> 1988607 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1988607 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988607 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1988607 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988607 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988615 INFO (zkCallback-26617-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b4189e name:ZooKeeperConnection Watcher:127.0.0.1:32965 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988615 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988615 INFO (jetty-launcher-26599-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988622 INFO (zkCallback-26616-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7efcde name:ZooKeeperConnection Watcher:127.0.0.1:32965 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988623 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988623 INFO (zkCallback-26618-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@185ce55 name:ZooKeeperConnection Watcher:127.0.0.1:32965 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988623 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988623 INFO (jetty-launcher-26599-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988623 INFO (jetty-launcher-26599-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988623 INFO (zkCallback-26620-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@84dd25 name:ZooKeeperConnection Watcher:127.0.0.1:32965 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988623 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988623 INFO (zkCallback-26619-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@18c4752 name:ZooKeeperConnection Watcher:127.0.0.1:32965 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988623 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988623 INFO (jetty-launcher-26599-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988624 INFO (jetty-launcher-26599-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1988638 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$74/16420922@18d1f07
[junit4] 2> 1988639 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988642 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$74/16420922@114ec15
[junit4] 2> 1988642 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988645 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$74/16420922@a26708
[junit4] 2> 1988646 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16f1f5d name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988646 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988646 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988647 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x156d7cd9a8a0009, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1988647 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$74/16420922@4ab02e
[junit4] 2> 1988647 INFO (zkCallback-26622-thread-1-processing-n:127.0.0.1:41996_solr) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@57c080 name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988647 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$74/16420922@12d59a6
[junit4] 2> 1988648 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988648 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988648 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1988651 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1de6a9e name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988651 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988654 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1988654 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14546b3 name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988654 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988654 INFO (zkCallback-26625-thread-1-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a0731c name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1988654 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1988657 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1988659 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1988659 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1988659 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1988683 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1988684 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1988686 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1988686 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1988686 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1988747 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1988747 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1988776 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1988776 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1988776 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1988797 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1988799 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1988800 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1988800 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1988800 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1988820 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1988823 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1988825 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1988825 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1988825 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1988829 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1988831 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1988833 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1988833 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1988833 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1988836 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1988839 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1988840 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1988840 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1988843 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1988845 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1988845 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1988845 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1988845 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1988850 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1988850 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1988850 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1988850 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1988850 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1988854 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1988854 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1988854 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1988854 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1988854 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1988857 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1988857 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1988857 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1988859 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1988859 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1988859 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1988859 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1988859 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1988859 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1988860 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1988860 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1988860 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1988860 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1988860 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1988860 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1988862 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1988862 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1988862 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1988863 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1988863 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1988868 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1988869 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1988869 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1988869 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1988869 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1988873 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1988873 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1988874 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1988874 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1988889 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1988889 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501720096047118-127.0.0.1:37379_solr-n_0000000000
[junit4] 2> 1988890 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501720096047115-127.0.0.1:44583_solr-n_0000000001
[junit4] 2> 1988891 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501720096047117-127.0.0.1:40947_solr-n_0000000002
[junit4] 2> 1988891 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501720096047116-127.0.0.1:41996_solr-n_0000000003
[junit4] 2> 1988893 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501720096047119-127.0.0.1:44636_solr-n_0000000004
[junit4] 2> 1988893 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37379_solr
[junit4] 2> 1988893 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1988893 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96501720096047118-127.0.0.1:37379_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96501720096047115-127.0.0.1:44583_solr-n_0000000001 to know if I could be the leader
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96501720096047116-127.0.0.1:41996_solr-n_0000000003 to know if I could be the leader
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96501720096047117-127.0.0.1:40947_solr-n_0000000002 to know if I could be the leader
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44583_solr
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44583_solr
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44636_solr
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44636_solr
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41996_solr
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40947_solr
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:41996_solr
[junit4] 2> 1988894 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40947_solr
[junit4] 2> 1988896 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.Overseer Overseer (id=96501720096047118-127.0.0.1:37379_solr-n_0000000000) starting
[junit4] 2> 1988898 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] 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> 1988898 INFO (zkCallback-26625-thread-1-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] 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> 1988898 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] 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> 1988898 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] 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> 1988898 INFO (zkCallback-26622-thread-1-processing-n:127.0.0.1:41996_solr) [n:127.0.0.1:41996_solr ] 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> 1988902 INFO (zkCallback-26625-thread-1-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1988902 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1988902 INFO (zkCallback-26622-thread-1-processing-n:127.0.0.1:41996_solr) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1988902 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1988902 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1988906 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1988939 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1988940 INFO (OverseerCollectionConfigSetProcessor-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1988940 INFO (OverseerStateUpdate-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1988941 INFO (OverseerStateUpdate-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (4)
[junit4] 2> 1988953 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1988953 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37379_solr as DOWN
[junit4] 2> 1988953 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1988953 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node2/.
[junit4] 2> 1988954 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1988954 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1988954 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1988954 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node4/.
[junit4] 2> 1988955 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1988955 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1988955 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1988955 INFO (jetty-launcher-26599-thread-4) [n:127.0.0.1:41996_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1988955 INFO (jetty-launcher-26599-thread-2) [n:127.0.0.1:44583_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1988957 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37379_solr
[junit4] 2> 1988957 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37379_solr
[junit4] 2> 1988958 INFO (OverseerStateUpdate-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:37379_solr"} current state version: 0
[junit4] 2> 1988958 INFO (OverseerStateUpdate-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:37379_solr
[junit4] 2> 1988962 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1988962 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1988962 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node1/.
[junit4] 2> 1988963 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1988966 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1988966 INFO (zkCallback-26625-thread-1-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1988966 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1988966 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1988966 INFO (jetty-launcher-26599-thread-1) [n:127.0.0.1:40947_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1988966 INFO (zkCallback-26622-thread-1-processing-n:127.0.0.1:41996_solr) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1988966 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1988967 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1988974 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1988974 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1988974 INFO (zkCallback-26625-thread-1-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1988975 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1988975 INFO (zkCallback-26622-thread-1-processing-n:127.0.0.1:41996_solr) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1988975 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1988975 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node3/.
[junit4] 2> 1988975 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1988976 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1988976 INFO (jetty-launcher-26599-thread-3) [n:127.0.0.1:44636_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1989005 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1989005 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1989006 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node5/.
[junit4] 2> 1989006 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1989007 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1989007 INFO (jetty-launcher-26599-thread-5) [n:127.0.0.1:37379_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1989007 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1989007 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1989017 INFO (zkCallback-26631-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1072e0 name:ZooKeeperConnection Watcher:127.0.0.1:32965 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1989017 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1989017 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1989023 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@180b301{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1989023 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=26822258
[junit4] 2> 1989023 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:41996_solr as DOWN
[junit4] 2> 1989028 INFO (OverseerStateUpdate-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:41996_solr"} current state version: 0
[junit4] 2> 1989028 INFO (OverseerStateUpdate-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:41996_solr
[junit4] 2> 1989030 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1989030 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1989030 INFO (zkCallback-26622-thread-1-processing-n:127.0.0.1:41996_solr) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1989031 WARN (zkCallback-26622-thread-1-processing-n:127.0.0.1:41996_solr) [n:127.0.0.1:41996_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1989031 INFO (zkCallback-26625-thread-2-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1989030 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1989031 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1989031 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1de746f{/solr,null,UNAVAILABLE}
[junit4] 2> 1989032 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1989032 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1989032 INFO (zkCallback-26625-thread-2-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1989032 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1989033 INFO (zkCallback-26625-thread-1-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96501720096047117-127.0.0.1:40947_solr-n_0000000002 to know if I could be the leader
[junit4] 2> 1989034 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@566477{/solr,null,AVAILABLE}
[junit4] 2> 1989035 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@866458{HTTP/1.1,[http/1.1]}{127.0.0.1:45331}
[junit4] 2> 1989035 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.e.j.s.Server Started @1990521ms
[junit4] 2> 1989035 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45331}
[junit4] 2> 1989035 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93
[junit4] 2> 1989035 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node6'
[junit4] 2> 1989035 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1989035 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1989036 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1989036 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1989038 INFO (zkCallback-26633-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@190cc9f name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1989038 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1989039 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1989039 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1989046 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node6/.
[junit4] 2> 1989046 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.CoreContainer New CoreContainer 8856066
[junit4] 2> 1989046 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node6]
[junit4] 2> 1989046 WARN (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node6/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node6/lib
[junit4] 2> 1989047 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] 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,
[junit4] 2> 1989049 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1989049 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1989049 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1989049 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32965/solr
[junit4] 2> 1989049 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1989049 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1989049 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1989052 INFO (zkCallback-26636-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e7b624 name:ZooKeeperConnection Watcher:127.0.0.1:32965 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1989052 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1989052 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1989054 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$74/16420922@9e0da5
[junit4] 2> 1989055 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1989058 INFO (zkCallback-26637-thread-1-processing-n:127.0.0.1:45331_solr) [n:127.0.0.1:45331_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ec3eed name:ZooKeeperConnection Watcher:127.0.0.1:32965/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1989058 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1989072 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1989073 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1989073 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1989075 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1989077 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96501720096047123-127.0.0.1:45331_solr-n_0000000005
[junit4] 2> 1989077 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96501720096047119-127.0.0.1:44636_solr-n_0000000004 to know if I could be the leader
[junit4] 2> 1989078 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:45331_solr as DOWN
[junit4] 2> 1989094 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45331_solr
[junit4] 2> 1989094 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:45331_solr
[junit4] 2> 1989095 INFO (OverseerStateUpdate-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:45331_solr"} current state version: 0
[junit4] 2> 1989095 INFO (OverseerStateUpdate-96501720096047118-127.0.0.1:37379_solr-n_0000000000) [n:127.0.0.1:37379_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:45331_solr
[junit4] 2> 1989120 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1989120 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1989120 INFO (zkCallback-26625-thread-1-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1989120 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1989120 INFO (zkCallback-26637-thread-1-processing-n:127.0.0.1:45331_solr) [n:127.0.0.1:45331_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1989122 INFO (zkCallback-26621-thread-1-processing-n:127.0.0.1:44583_solr) [n:127.0.0.1:44583_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1989122 INFO (zkCallback-26623-thread-1-processing-n:127.0.0.1:40947_solr) [n:127.0.0.1:40947_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1989122 INFO (zkCallback-26624-thread-1-processing-n:127.0.0.1:37379_solr) [n:127.0.0.1:37379_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1989122 INFO (zkCallback-26625-thread-1-processing-n:127.0.0.1:44636_solr) [n:127.0.0.1:44636_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1989125 INFO (zkCallback-26637-thread-1-processing-n:127.0.0.1:45331_solr) [n:127.0.0.1:45331_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1989168 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1989168 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1989169 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001/tempDir-001/node6/.
[junit4] 2> 1989169 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1989170 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1989170 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[D92FD8A0EF60BDC]) [n:127.0.0.1:45331_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1989171 INFO (TEST-BasicAuthIntegrationTest.testBa
[...truncated too long message...]
g.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:755)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1161)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1050)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:992)
[junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] > at org.apache.solr.security.BasicAuthIntegrationTest.doExtraTests(BasicAuthIntegrationTest.java:193)
[junit4] > at org.apache.solr.cloud.TestMiniSolrCloudClusterBase.testCollectionCreateSearchDelete(TestMiniSolrCloudClusterBase.java:196)
[junit4] > at org.apache.solr.cloud.TestMiniSolrCloudClusterBase.testBasics(TestMiniSolrCloudClusterBase.java:79)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:37379/solr/testSolrCloudCollection_shard1_replica1
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:622)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:415)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:367)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:730)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > ... 1 more
[junit4] > Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:37379 failed to respond
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:513)
[junit4] > ... 10 more
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_D92FD8A0EF60BDC-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {range_facet_l_dv=PostingsFormat(name=Asserting), _version_=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=PostingsFormat(name=MockRandom), id=PostingsFormat(name=Asserting), range_facet_i_dv=PostingsFormat(name=MockRandom), range_facet_l=PostingsFormat(name=MockRandom), timestamp=PostingsFormat(name=MockRandom)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1563, maxMBSortInHeap=5.957320628333271, sim=ClassicSimilarity, locale=pt, timezone=Canada/East-Saskatchewan
[junit4] 2> NOTE: Linux 4.4.0-31-generic i386/Oracle Corporation 1.8.0_102 (32-bit)/cpus=12,threads=1,free=259467456,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestConfigSets, TestJmxIntegration, TestLRUStatsCache, ConfigSetsAPITest, CircularListTest, SimpleFacetsTest, TestConfigSetsAPI, TestSolr4Spatial2, ClusterStateTest, TestJettySolrRunner, TolerantUpdateProcessorTest, SliceStateTest, SearchHandlerTest, LeaderInitiatedRecoveryOnCommitTest, CurrencyFieldXmlFileTest, TestAnalyzeInfixSuggestions, DistributedQueryComponentOptimizationTest, TestRandomRequestDistribution, CollectionsAPIAsyncDistributedZkTest, TestFieldResource, TestSolrQueryParserResource, QueryParsingTest, CoreAdminHandlerTest, TestAddFieldRealTimeGet, TestMiniSolrCloudClusterSSL, TestSolrDeletionPolicy1, UnloadDistributedZkTest, TestInitQParser, DirectSolrConnectionTest, TestRestoreCore, TestFreeTextSuggestions, MergeStrategyTest, StatelessScriptUpdateProcessorFactoryTest, TestCoreDiscovery, TestSolrDynamicMBean, ChangedSchemaMergeTest, TestStressRecovery, TestReloadAndDeleteDocs, RegexBoostProcessorTest, TestSchemaNameResource, TlogReplayBufferedWhileIndexingTest, TestObjectReleaseTracker, TestElisionMultitermQuery, FieldAnalysisRequestHandlerTest, NoCacheHeaderTest, BlockDirectoryTest, TestSort, TestTrackingShardHandlerFactory, TestWriterPerf, TestLegacyFieldCache, ShowFileRequestHandlerTest, TestSolrJ, SolrTestCaseJ4Test, TestDynamicFieldCollectionResource, TestSha256AuthenticationProvider, SuggesterTest, CollectionReloadTest, HdfsSyncSliceTest, TestComplexPhraseQParserPlugin, TestExclusionRuleCollectionAccess, TestLeaderInitiatedRecoveryThread, DebugComponentTest, AnalyticsMergeStrategyTest, CloneFieldUpdateProcessorFactoryTest, DirectUpdateHandlerOptimizeTest, TestCryptoKeys, HighlighterConfigTest, UpdateParamsTest, PrimitiveFieldTypeTest, BinaryUpdateRequestHandlerTest, SuggesterWFSTTest, MultiThreadedOCPTest, OverseerStatusTest, TestIntervalFaceting, HdfsLockFactoryTest, TestCloudManagedSchema, TestShortCircuitedRequests, BadComponentTest, SolrCLIZkUtilsTest, TestClusterStateMutator, DistributedFacetPivotLargeTest, TestCollationFieldDocValues, TestFilteredDocIdSet, TestPHPSerializedResponseWriter, TestZkChroot, ForceLeaderTest, FieldMutatingUpdateProcessorTest, TestStressUserVersions, TestSolrConfigHandlerConcurrent, AddSchemaFieldsUpdateProcessorFactoryTest, TestStressReorder, TestRawResponseWriter, CdcrUpdateLogTest, TestIndexSearcher, TestIndexingPerformance, ReplicationFactorTest, DeleteNodeTest, TestMiniSolrCloudCluster, TestStressLiveNodes, TestInfoStreamLogging, TestDistributedMissingSort, TestSuggestSpellingConverter, TestAuthenticationFramework, TestExtendedDismaxParser, BasicDistributedZk2Test, SyncSliceTest, OverseerTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, TestRecovery, TestRealTimeGet, HardAutoCommitTest, TestGroupingSearch, BadIndexSchemaTest, TestFunctionQuery, DirectUpdateHandlerTest, SolrRequestParserTest, SpatialFilterTest, SolrCoreCheckLockOnStartupTest, DefaultValueUpdateProcessorTest, DocValuesMultiTest, CacheHeaderTest, DisMaxRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, FileBasedSpellCheckerTest, MoreLikeThisHandlerTest, TestSolrQueryParser, TestCollationField, TestCSVResponseWriter, TestComponentsName, TestQuerySenderListener, TestQuerySenderNoQuery, MultiTermTest, TestDocSet, NumericFieldsTest, TestSolrCoreProperties, TestXIncludeConfig, TestPerFieldSimilarity, TestFastOutputStream, TestFastLRUCache, PreAnalyzedFieldTest, DateFieldTest, RAMDirectoryFactoryTest, TestUtils, ZkNodePropsTest, TestRTGBase, CursorPagingTest, TestHighlightDedupGrouping, TestTolerantSearch, AssignTest, AsyncCallRequestStatusResponseTest, CollectionStateFormat2Test, CustomCollectionTest, LeaderFailoverAfterPartitionTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerTaskQueueTest, PeerSyncReplicationTest, SolrXmlInZkTest, TestCloudDeleteByQuery, TestConfigSetsAPIExclusivity, TestDownShardTolerantSearch, TestHdfsCloudBackupRestore, TestLeaderElectionZkExpiry, TestMiniSolrCloudClusterBase, TestStressCloudBlindAtomicUpdates, VMParamsZkACLAndCredentialsProvidersTest, HdfsChaosMonkeySafeLeaderTest, HdfsUnloadDistributedZkTest, BlobRepositoryCloudTest, RequestLoggingTest, TestHdfsBackupRestoreCore, TestReqParamsAPI, DistributedFacetPivotSmallAdvancedTest, DistributedFacetPivotSmallTest, FacetPivotSmallTest, SpatialHeatmapFacetsTest, TestDefaultSearchFieldResource, TestFieldTypeResource, TestSchemaSimilarityResource, TestSolrQueryParserDefaultOperatorResource, EnumFieldTest, SpatialRPTFieldTypeTest, TestManagedSchemaAPI, TestSchemaManager, TestUseDocValuesAsStored2, AnalyticsQueryTest, RankQueryTest, TestGraphTermsQParserPlugin, TestHashQParserPlugin, TestMaxScoreQueryParser, TestMissingGroups, TestQueryWrapperFilter, TestRandomCollapseQParserPlugin, TestScoreJoinQPScore, TestJsonRequest, CloudMLTQParserTest, BasicAuthIntegrationTest]
[junit4] Completed [597/632 (1!)] on J2 in 19.93s, 1 test, 1 error <<< FAILURES!
[...truncated 52556 lines...]