Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-MacOSX/1942/ Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.cloud.TestRebalanceLeaders.test Error Message: No live SolrServers available to handle this request:[http://127.0.0.1:61172/_xht, http://127.0.0.1:61175/_xht, http://127.0.0.1:61181/_xht, http://127.0.0.1:61178/_xht, http://127.0.0.1:61167/_xht] Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:61172/_xht, http://127.0.0.1:61175/_xht, http://127.0.0.1:61181/_xht, http://127.0.0.1:61178/_xht, http://127.0.0.1:61167/_xht] at __randomizedtesting.SeedInfo.seed([F335E01983098E09:7B61DFC32DF5E3F1]:0) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:349) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1009) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:787) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:730) at org.apache.solr.cloud.TestRebalanceLeaders.issueCommands(TestRebalanceLeaders.java:281) at org.apache.solr.cloud.TestRebalanceLeaders.rebalanceLeaderTest(TestRebalanceLeaders.java:108) at org.apache.solr.cloud.TestRebalanceLeaders.test(TestRebalanceLeaders.java:74) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) 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:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:61175/_xht: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 500 {trace=java.lang.AssertionError at org.apache.solr.common.util.ByteUtils.UTF8toUTF16(ByteUtils.java:36) at org.apache.solr.common.util.ByteUtils.UTF8toUTF16(ByteUtils.java:64) at org.apache.solr.common.cloud.ZkStateReader.fromJSON(ZkStateReader.java:140) at org.apache.solr.common.cloud.ZkNodeProps.load(ZkNodeProps.java:92) at org.apache.solr.cloud.DistributedQueue.containsTaskWithRequestId(DistributedQueue.java:127) at org.apache.solr.handler.admin.CollectionsHandler.overseerCollectionQueueContains(CollectionsHandler.java:687) at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:709) at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:692) at org.apache.solr.handler.admin.CollectionsHandler.rejoinElection(CollectionsHandler.java:487) at org.apache.solr.handler.admin.CollectionsHandler.makeReplicaFirstWatcher(CollectionsHandler.java:422) at org.apache.solr.handler.admin.CollectionsHandler.insurePreferredIsLeader(CollectionsHandler.java:398) at org.apache.solr.handler.admin.CollectionsHandler.handleBalanceLeaders(CollectionsHandler.java:309) at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:275) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:144) at org.apache.solr.servlet.SolrDispatchFilter.handleAdminRequest(SolrDispatchFilter.java:736) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:261) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:204) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:142) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235) at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82) at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628) at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) at java.lang.Thread.run(Thread.java:745) ,code=500}</title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing /_xht/admin/collections. Reason: <pre> {trace=java.lang.AssertionError at org.apache.solr.common.util.ByteUtils.UTF8toUTF16(ByteUtils.java:36) at org.apache.solr.common.util.ByteUtils.UTF8toUTF16(ByteUtils.java:64) at org.apache.solr.common.cloud.ZkStateReader.fromJSON(ZkStateReader.java:140) at org.apache.solr.common.cloud.ZkNodeProps.load(ZkNodeProps.java:92) at org.apache.solr.cloud.DistributedQueue.containsTaskWithRequestId(DistributedQueue.java:127) at org.apache.solr.handler.admin.CollectionsHandler.overseerCollectionQueueContains(CollectionsHandler.java:687) at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:709) at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:692) at org.apache.solr.handler.admin.CollectionsHandler.rejoinElection(CollectionsHandler.java:487) at org.apache.solr.handler.admin.CollectionsHandler.makeReplicaFirstWatcher(CollectionsHandler.java:422) at org.apache.solr.handler.admin.CollectionsHandler.insurePreferredIsLeader(CollectionsHandler.java:398) at org.apache.solr.handler.admin.CollectionsHandler.handleBalanceLeaders(CollectionsHandler.java:309) at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:275) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:144) at org.apache.solr.servlet.SolrDispatchFilter.handleAdminRequest(SolrDispatchFilter.java:736) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:261) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:204) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:142) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235) at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82) at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628) at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) at java.lang.Thread.run(Thread.java:745) ,code=500}</pre></p> <hr /><i><small>Powered by Jetty://</small></i> </body> </html> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:527) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:338) ... 48 more Build Log: [...truncated 10056 lines...] [junit4] Suite: org.apache.solr.cloud.TestRebalanceLeaders [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/init-core-data-001 [junit4] 2> 5518579 T19589 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 5518579 T19589 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /_xht/ [junit4] 2> 5518585 T19589 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 5518587 T19590 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 5518688 T19589 oasc.ZkTestServer.run start zk server on port:61164 [junit4] 2> 5518689 T19589 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5518692 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5518702 T19597 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2de3e490 name:ZooKeeperConnection Watcher:127.0.0.1:61164 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5518703 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5518704 T19589 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5518705 T19589 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 5518715 T19589 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5518717 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5518723 T19600 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7fee3060 name:ZooKeeperConnection Watcher:127.0.0.1:61164/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5518723 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5518724 T19589 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5518724 T19589 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 5518735 T19589 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 5518744 T19589 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 5518750 T19589 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 5518757 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 5518758 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 5518770 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 5518771 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 5518780 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 5518781 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 5518790 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 5518790 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 5518799 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 5518799 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 5518808 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 5518809 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 5518819 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 5518819 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 5518829 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 5518830 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 5518838 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 5518838 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 5518846 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 5518846 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 5518856 T19589 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 5518857 T19589 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 5520658 T19589 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/cores/collection1 [junit4] 2> 5520661 T19589 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5520666 T19589 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61167 [junit4] 2> 5520667 T19589 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@2876b359 [junit4] 2> 5520668 T19589 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5520668 T19589 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001 [junit4] 2> 5520669 T19589 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/' [junit4] 2> 5520714 T19589 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/solr.xml [junit4] 2> 5520772 T19589 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/cores [junit4] 2> 5520773 T19589 oasc.CoreContainer.<init> New CoreContainer 50790005 [junit4] 2> 5520773 T19589 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/] [junit4] 2> 5520774 T19589 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5520775 T19589 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5520775 T19589 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5520775 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5520775 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5520776 T19589 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5520776 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5520776 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5520777 T19589 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5520777 T19589 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5520777 T19589 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5520778 T19589 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5520779 T19589 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5520779 T19589 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5520779 T19589 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5520780 T19589 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61164/solr [junit4] 2> 5520780 T19589 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5520781 T19589 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5520783 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5520787 T19612 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3773c3b0 name:ZooKeeperConnection Watcher:127.0.0.1:61164 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5520788 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5520788 T19589 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5520792 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5520796 T19615 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c159473 name:ZooKeeperConnection Watcher:127.0.0.1:61164/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5520797 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5520801 T19589 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 5520809 T19589 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 5520817 T19589 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 5520825 T19589 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 5520837 T19589 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 5520850 T19589 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 5520855 T19589 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61167__xht [junit4] 2> 5520857 T19589 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61167__xht [junit4] 2> 5520866 T19589 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 5520871 T19589 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 5520876 T19589 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5520880 T19589 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:61167__xht [junit4] 2> 5520881 T19589 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 5520886 T19589 oasc.Overseer.start Overseer (id=93286269216096259-127.0.0.1:61167__xht-n_0000000000) starting [junit4] 2> 5520893 T19589 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 5520917 T19589 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 5520918 T19617 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 5520920 T19589 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 5520926 T19589 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 5520931 T19589 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5520940 T19616 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 5520944 T19589 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/cores [junit4] 2> 5520946 T19589 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/cores/collection1/ [junit4] 2> 5520946 T19589 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5520949 T19619 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 5520949 T19619 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5520951 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5520951 T19619 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5520961 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "shard":null, [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:61167/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:61167__xht", [junit4] 2> "collection":"control_collection"} current state version: 0 [junit4] 2> 5520962 T19616 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "shard":null, [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:61167/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:61167__xht", [junit4] 2> "collection":"control_collection"} [junit4] 2> 5520963 T19616 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 5520963 T19616 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 5520966 T19615 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 5521953 T19619 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5521954 T19619 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 5521955 T19619 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5521956 T19619 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5521959 T19619 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5521960 T19619 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/cores/collection1/' [junit4] 2> 5521981 T19619 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5521987 T19619 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5522000 T19619 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5522021 T19619 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5522068 T19619 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5522072 T19619 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5522081 T19619 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5522470 T19619 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5522472 T19619 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5522474 T19619 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5522480 T19619 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5522541 T19619 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 5522541 T19619 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5522542 T19619 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/control-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/control/data/ [junit4] 2> 5522542 T19619 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@30d671c4 [junit4] 2> 5522543 T19619 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/control/data [junit4] 2> 5522543 T19619 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/control/data/index/ [junit4] 2> 5522543 T19619 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 5522544 T19619 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/control/data/index [junit4] 2> 5522544 T19619 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7769887558219658] [junit4] 2> 5522546 T19619 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14e0b23d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2bd5cd80),segFN=segments_1,generation=1} [junit4] 2> 5522546 T19619 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5522551 T19619 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5522552 T19619 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5522552 T19619 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5522552 T19619 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5522552 T19619 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5522552 T19619 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5522553 T19619 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5522554 T19619 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5522554 T19619 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5522555 T19619 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5522556 T19619 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5522556 T19619 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5522557 T19619 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5522557 T19619 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5522558 T19619 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5522559 T19619 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5522559 T19619 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5522560 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5522562 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5522562 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5522563 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5522563 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5522564 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5522564 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5522565 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5522566 T19619 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5522566 T19619 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5522585 T19619 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5522587 T19619 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5522589 T19619 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5522591 T19619 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5522593 T19619 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 5522596 T19619 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5522596 T19619 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5522597 T19619 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=31.6630859375, floorSegmentMB=0.6806640625, forceMergeDeletesPctAllowed=1.6503686984821198, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5929411491058365 [junit4] 2> 5522597 T19619 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14e0b23d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2bd5cd80),segFN=segments_1,generation=1} [junit4] 2> 5522597 T19619 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5522598 T19619 oass.SolrIndexSearcher.<init> Opening Searcher@5faf68df[collection1] main [junit4] 2> 5522598 T19619 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5522601 T19619 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5522601 T19619 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5522602 T19619 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5522602 T19619 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5522603 T19619 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5522604 T19619 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5522605 T19619 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5522605 T19619 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5522605 T19619 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 5522607 T19619 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5522608 T19620 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5faf68df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5522609 T19623 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:61167/_xht collection:control_collection shard:shard1 [junit4] 2> 5522609 T19589 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 5522610 T19589 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5522610 T19589 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5522611 T19623 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 5522612 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5522616 T19626 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@34e05294 name:ZooKeeperConnection Watcher:127.0.0.1:61164/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5522616 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5522617 T19589 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5522619 T19589 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5522624 T19623 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 5522627 T19589 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 5522628 T19589 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 5522628 T19589 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5522628 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5522630 T19623 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5522630 T19623 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4548 name=collection1 org.apache.solr.core.SolrCore@643fc89d url=http://127.0.0.1:61167/_xht/collection1 node=127.0.0.1:61167__xht C4548_STATE=coll:control_collection core:collection1 props:{base_url=http://127.0.0.1:61167/_xht, core=collection1, state=down, node_name=127.0.0.1:61167__xht} [junit4] 2> 5522631 T19623 C4548 P61167 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61167/_xht/collection1/ [junit4] 2> 5522631 T19623 C4548 P61167 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5522631 T19623 C4548 P61167 oasc.SyncStrategy.syncToMe http://127.0.0.1:61167/_xht/collection1/ has no replicas [junit4] 2> 5522632 T19623 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61167/_xht/collection1/ shard1 [junit4] 2> 5522633 T19623 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 5522630 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5522634 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 5522636 T19629 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@547ae45f name:ZooKeeperConnection Watcher:127.0.0.1:61164/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5522637 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5522637 T19589 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5522639 T19626 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 5522639 T19615 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 5522652 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"4", [junit4] 2> "stateFormat":"2"} current state version: 2 [junit4] 2> 5522653 T19616 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 5522661 T19616 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json [junit4] 2> 5522669 T19630 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5522673 T19626 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 5522680 T19616 oasc.Overseer$ClusterStateUpdater.run 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:61167/_xht";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 3 [junit4] 2> 5522684 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5522687 T19615 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 5522691 T19626 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 5522707 T19623 oasc.ZkController.register We are http://127.0.0.1:61167/_xht/collection1/ and leader is http://127.0.0.1:61167/_xht/collection1/ [junit4] 2> 5522708 T19623 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61167/_xht [junit4] 2> 5522708 T19623 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5522708 T19623 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 5522708 T19623 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5522711 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5522723 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "shard":"shard1", [junit4] 2> "numShards":"4", [junit4] 2> "base_url":"http://127.0.0.1:61167/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1:61167__xht", [junit4] 2> "collection":"control_collection"} current state version: 4 [junit4] 2> 5522724 T19616 oasco.ReplicaMutator.updateState Update state numShards=4 message={ [junit4] 2> "shard":"shard1", [junit4] 2> "numShards":"4", [junit4] 2> "base_url":"http://127.0.0.1:61167/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1:61167__xht", [junit4] 2> "collection":"control_collection"} [junit4] 2> 5522832 T19615 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 5522832 T19626 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 5524396 T19589 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/cores/collection1 [junit4] 2> 5524400 T19589 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 5524401 T19589 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5524404 T19589 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61172 [junit4] 2> 5524405 T19589 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@2876b359 [junit4] 2> 5524405 T19589 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5524406 T19589 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001 [junit4] 2> 5524406 T19589 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/' [junit4] 2> 5524450 T19589 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/solr.xml [junit4] 2> 5524488 T19589 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/cores [junit4] 2> 5524489 T19589 oasc.CoreContainer.<init> New CoreContainer 1487987962 [junit4] 2> 5524489 T19589 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/] [junit4] 2> 5524491 T19589 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5524491 T19589 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5524492 T19589 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5524492 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5524493 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5524493 T19589 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5524494 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5524494 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5524494 T19589 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5524495 T19589 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5524495 T19589 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5524496 T19589 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5524496 T19589 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5524496 T19589 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5524497 T19589 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5524497 T19589 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61164/solr [junit4] 2> 5524497 T19589 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5524497 T19589 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5524499 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5524503 T19642 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a7bc43a name:ZooKeeperConnection Watcher:127.0.0.1:61164 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5524503 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5524504 T19589 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5524508 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5524512 T19645 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47bdad41 name:ZooKeeperConnection Watcher:127.0.0.1:61164/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5524512 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5524531 T19589 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5525548 T19589 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61172__xht [junit4] 2> 5525550 T19589 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61172__xht [junit4] 2> 5525564 T19589 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5525572 T19589 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/cores [junit4] 2> 5525575 T19589 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/cores/collection1/ [junit4] 2> 5525575 T19589 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5525585 T19646 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5525586 T19646 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5525590 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5525593 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "shard":null, [junit4] 2> "numShards":"4", [junit4] 2> "base_url":"http://127.0.0.1:61172/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:61172__xht", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 5525593 T19646 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 5525593 T19646 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 5525594 T19616 oasco.ReplicaMutator.updateState Update state numShards=4 message={ [junit4] 2> "shard":null, [junit4] 2> "numShards":"4", [junit4] 2> "base_url":"http://127.0.0.1:61172/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:61172__xht", [junit4] 2> "collection":"collection1"} [junit4] 2> 5525594 T19616 oasco.ReplicaMutator.updateState Collection already exists with numShards=4 [junit4] 2> 5525595 T19616 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 5525595 T19646 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 5525598 T19616 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 5525599 T19646 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 5525600 T19646 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5525601 T19645 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5525602 T19645 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 5526603 T19646 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5526604 T19646 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5526606 T19646 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5526607 T19646 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5526612 T19646 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5526613 T19646 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/cores/collection1/' [junit4] 2> 5526640 T19646 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5526645 T19646 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5526658 T19646 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5526697 T19646 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5526751 T19646 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5526754 T19646 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5526765 T19646 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5527124 T19646 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5527127 T19646 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5527128 T19646 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5527134 T19646 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5527191 T19646 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5527191 T19646 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5527191 T19646 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-1-001/cores/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/jetty1/ [junit4] 2> 5527192 T19646 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@30d671c4 [junit4] 2> 5527193 T19646 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/jetty1 [junit4] 2> 5527193 T19646 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/jetty1/index/ [junit4] 2> 5527193 T19646 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 5527194 T19646 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/tempDir-001/jetty1/index [junit4] 2> 5527195 T19646 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7769887558219658] [junit4] 2> 5527195 T19646 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16c08dc9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3b9c92ca),segFN=segments_1,generation=1} [junit4] 2> 5527195 T19646 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5527201 T19646 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5527202 T19646 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5527202 T19646 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5527202 T19646 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5527203 T19646 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5527203 T19646 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5527203 T19646 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5527204 T19646 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5527204 T19646 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5527204 T19646 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5527205 T19646 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5527205 T19646 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5527206 T19646 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5527207 T19646 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5527207 T19646 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5527208 T19646 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5527208 T19646 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5527209 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5527214 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5527215 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5527215 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5527216 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5527216 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5527217 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5527217 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5527218 T19646 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5527218 T19646 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5527231 T19646 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5527233 T19646 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5527236 T19646 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5527238 T19646 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5527239 T19646 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 5527242 T19646 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5527242 T19646 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5527243 T19646 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=39, maxMergedSegmentMB=31.6630859375, floorSegmentMB=0.6806640625, forceMergeDeletesPctAllowed=1.6503686984821198, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5929411491058365 [junit4] 2> 5527244 T19646 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@16c08dc9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3b9c92ca),segFN=segments_1,generation=1} [junit4] 2> 5527244 T19646 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5527244 T19646 oass.SolrIndexSearcher.<init> Opening Searcher@302dbb21[collection1] main [junit4] 2> 5527244 T19646 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5527248 T19646 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5527248 T19646 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5527250 T19646 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5527250 T19646 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5527250 T19646 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5527252 T19646 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5527253 T19646 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5527253 T19646 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5527253 T19646 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 5527255 T19647 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@302dbb21[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5527255 T19646 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5527257 T19650 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:61172/_xht collection:collection1 shard:shard3 [junit4] 2> 5527258 T19589 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 5527258 T19589 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5527259 T19650 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 5527271 T19650 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 5527275 T19650 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5527275 T19650 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C4549 name=collection1 org.apache.solr.core.SolrCore@41c6bf2f url=http://127.0.0.1:61172/_xht/collection1 node=127.0.0.1:61172__xht C4549_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:61172/_xht, core=collection1, state=down, node_name=127.0.0.1:61172__xht} [junit4] 2> 5527275 T19650 C4549 P61172 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61172/_xht/collection1/ [junit4] 2> 5527276 T19650 C4549 P61172 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5527276 T19650 C4549 P61172 oasc.SyncStrategy.syncToMe http://127.0.0.1:61172/_xht/collection1/ has no replicas [junit4] 2> 5527276 T19650 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61172/_xht/collection1/ shard3 [junit4] 2> 5527276 T19650 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 5527288 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5527291 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 5527293 T19616 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 5527302 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:61172/_xht";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 5527307 T19616 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 5527310 T19645 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5527315 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5527322 T19645 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 5527339 T19650 oasc.ZkController.register We are http://127.0.0.1:61172/_xht/collection1/ and leader is http://127.0.0.1:61172/_xht/collection1/ [junit4] 2> 5527340 T19650 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61172/_xht [junit4] 2> 5527340 T19650 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5527340 T19650 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 5527340 T19650 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5527350 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5527356 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "shard":"shard3", [junit4] 2> "numShards":"4", [junit4] 2> "base_url":"http://127.0.0.1:61172/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1:61172__xht", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 5527357 T19616 oasco.ReplicaMutator.updateState Update state numShards=4 message={ [junit4] 2> "shard":"shard3", [junit4] 2> "numShards":"4", [junit4] 2> "base_url":"http://127.0.0.1:61172/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1:61172__xht", [junit4] 2> "collection":"collection1"} [junit4] 2> 5527358 T19616 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3 [junit4] 2> 5527370 T19650 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5527370 T19645 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5527371 T19645 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5529146 T19589 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001/cores/collection1 [junit4] 2> 5529149 T19589 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 5529150 T19589 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5529156 T19589 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:61175 [junit4] 2> 5529158 T19589 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@2876b359 [junit4] 2> 5529158 T19589 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5529158 T19589 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001 [junit4] 2> 5529159 T19589 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001/' [junit4] 2> 5529207 T19589 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001/solr.xml [junit4] 2> 5529253 T19589 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001/cores [junit4] 2> 5529253 T19589 oasc.CoreContainer.<init> New CoreContainer 668756682 [junit4] 2> 5529254 T19589 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001/] [junit4] 2> 5529255 T19589 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5529255 T19589 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5529255 T19589 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5529256 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5529256 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5529256 T19589 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5529257 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5529257 T19589 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5529257 T19589 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5529258 T19589 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5529258 T19589 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5529259 T19589 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5529260 T19589 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5529260 T19589 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5529260 T19589 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5529261 T19589 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61164/solr [junit4] 2> 5529261 T19589 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5529261 T19589 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5529263 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5529267 T19662 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@70ecc238 name:ZooKeeperConnection Watcher:127.0.0.1:61164 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5529268 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5529268 T19589 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5529275 T19589 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5529278 T19665 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@35053d9 name:ZooKeeperConnection Watcher:127.0.0.1:61164/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5529278 T19589 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5529297 T19589 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5530317 T19589 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61175__xht [junit4] 2> 5530318 T19589 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61175__xht [junit4] 2> 5530330 T19589 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5530335 T19589 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001/cores [junit4] 2> 5530338 T19589 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001/cores/collection1/ [junit4] 2> 5530338 T19589 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5530346 T19666 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5530347 T19666 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5530352 T19615 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5530353 T19666 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 5530353 T19666 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 5530354 T19616 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "shard":null, [junit4] 2> "numShards":"4", [junit4] 2> "base_url":"http://127.0.0.1:61175/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:61175__xht", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 5530354 T19666 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 5530355 T19616 oasco.ReplicaMutator.updateState Update state numShards=4 message={ [junit4] 2> "shard":null, [junit4] 2> "numShards":"4", [junit4] 2> "base_url":"http://127.0.0.1:61175/_xht";, [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:61175__xht", [junit4] 2> "collection":"collection1"} [junit4] 2> 5530356 T19616 oasco.ReplicaMutator.updateState Collection already exists with numShards=4 [junit4] 2> 5530356 T19616 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard4 [junit4] 2> 5530357 T19666 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5530357 T19666 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5530361 T19616 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4 [junit4] 2> 5530362 T19665 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5530363 T19645 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5530364 T19665 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 5530365 T19645 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 5531359 T19666 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5531360 T19666 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5531362 T19666 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5531362 T19666 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5531366 T19666 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5531367 T19666 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestRebalanceLeaders F335E01983098E09-001/shard-2-001/cores/collection1/' [junit4] 2> 5531397 T19666 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5531404 T19666 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5531420 T19666 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5531446 T19666 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5531492 T19666 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5531495 T19666 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5531509 T19666 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5531905 T19666 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5531907 T19666 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5531908 T19666 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5531926 T19666 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5531991 T19666 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5531991 T19666 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5531991 T19666 oas [...truncated too long message...] stributedZkTest, TestSolrConfigHandlerCloud, DirectSolrSpellCheckerTest, TestClusterStateMutator, ExternalFileFieldSortTest, TestRawResponseWriter, TestCloudPivotFacet, TestSerializedLuceneMatchVersion, SimpleMLTQParserTest, TestRTGBase, LukeRequestHandlerTest, TestSchemaSimilarityResource, TestAtomicUpdateErrorCases, HdfsBasicDistributedZkTest, StandardRequestHandlerTest, HdfsCollectionsAPIDistributedZkTest, QueryParsingTest, TestFreeTextSuggestions, TestSuggestSpellingConverter, StatelessScriptUpdateProcessorFactoryTest, TestCollationField, TestIntervalFaceting, ZkSolrClientTest, FieldMutatingUpdateProcessorTest, EnumFieldTest, AnalysisErrorHandlingTest, TestConfigOverlay, TestCSVLoader, DocValuesMissingTest, TestManagedResource, TestFieldTypeResource, JSONWriterTest, OverseerRolesTest, SolrTestCaseJ4Test, ResponseHeaderTest, SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, DeleteReplicaTest, DistributedIntervalFacetingTest, DistribDocExpirationUpdateProcessorTest, OpenCloseCoreStressTest, TestSolrDeletionPolicy1, TestPerFieldSimilarity, TestQueryUtils, AutoCommitTest, CoreAdminCreateDiscoverTest, CloudMLTQParserTest, TestXIncludeConfig, ReplicationFactorTest, PrimitiveFieldTypeTest, TestArbitraryIndexDir, FastVectorHighlighterTest, DistributedFacetPivotLargeTest, TestHighlightDedupGrouping, TestDocBasedVersionConstraints, TestClassNameShortening, TestTrie, HdfsRecoveryZkTest, TestDefaultSearchFieldResource, DateRangeFieldTest, CursorPagingTest, DistributedTermsComponentTest, TestPostingsSolrHighlighter, TestDocSet, TestReplicationHandler, HdfsDirectoryTest, TestElisionMultitermQuery, UniqFieldsUpdateProcessorFactoryTest, HighlighterConfigTest, TestCloudSchemaless, ShardRoutingCustomTest, DistributedDebugComponentTest, DocumentBuilderTest, TestLMDirichletSimilarityFactory, TestRealTimeGet, ZkCLITest, AtomicUpdatesTest, DistanceUnitsTest, BinaryUpdateRequestHandlerTest, PrimUtilsTest, SolrCoreCheckLockOnStartupTest, SearchHandlerTest, PeerSyncTest, SpatialRPTFieldTypeTest, TestIndexSearcher, TestRemoteStreaming, UUIDFieldTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSchemaVersionResource, TestAddFieldRealTimeGet, VMParamsZkACLAndCredentialsProvidersTest, TestWriterPerf, DisMaxRequestHandlerTest, TestFunctionQuery, TestConfigSets, ChangedSchemaMergeTest, TestBadConfig, TestFuzzyAnalyzedSuggestions, TestHighFrequencyDictionaryFactory, SolrCmdDistributorTest, TestRandomMergePolicy, TestSolrConfigHandlerConcurrent, TestCursorMarkWithoutUniqueKey, TestPHPSerializedResponseWriter, TestCopyFieldCollectionResource, TestPivotHelperCode, TestDFRSimilarityFactory, TestSurroundQueryParser, TestMaxScoreQueryParser, CustomCollectionTest, TestPartialUpdateDeduplication, QueryEqualityTest, ResourceLoaderTest, DistributedExpandComponentTest, TestJmxIntegration, TestQuerySenderNoQuery, TestSort, TestFieldCollectionResource, XmlUpdateRequestHandlerTest, TestConfigReload, ConvertedLegacyTest, TestSearcherReuse, AsyncMigrateRouteKeyTest, TestSearchPerf, NoCacheHeaderTest, TestDistributedMissingSort, BasicDistributedZkTest, UUIDUpdateProcessorFallbackTest, ClusterStateTest, LeaderElectionIntegrationTest, TestExactStatsCache, TestDistributedSearch, SolrRequestParserTest, FullSolrCloudDistribCmdsTest, DeleteShardTest, BlockDirectoryTest, PreAnalyzedUpdateProcessorTest, SSLMigrationTest, CurrencyFieldXmlFileTest, TestObjectReleaseTracker, SignatureUpdateProcessorFactoryTest, HdfsSyncSliceTest, RequestLoggingTest, TestCollapseQParserPlugin, TestRandomDVFaceting, CircularListTest, ZkNodePropsTest, TermVectorComponentTest, RankQueryTest, DistributedFacetPivotWhiteBoxTest, ExitableDirectoryReaderTest, SampleTest, TestFiltering, TestIBSimilarityFactory, TestReload, TestReplicationHandlerBackup, TestCollationFieldDocValues, ScriptEngineTest, DistanceFunctionTest, TestBinaryField, ShowFileRequestHandlerTest, SimplePostToolTest, TestFieldTypeCollectionResource, TestJoin, AnalyticsQueryTest, TestShardHandlerFactory, TestOrdValues, TestSchemaNameResource, TestAnalyzeInfixSuggestions, DistribJoinFromCollectionTest, NotRequiredUniqueKeyTest, AlternateDirectoryTest, TestRecoveryHdfs, WordBreakSolrSpellCheckerTest, TestImplicitCoreProperties, SpellPossibilityIteratorTest, TestSolrQueryParserDefaultOperatorResource, TestDynamicLoading, HdfsDirectoryFactoryTest, TestBM25SimilarityFactory, DistributedQueryElevationComponentTest, RecoveryAfterSoftCommitTest, DocValuesMultiTest, DistributedQueryComponentCustomSortTest, OverseerTest, DistributedMLTComponentTest, SuggestComponentTest, TimeZoneUtilsTest, TestCloudManagedSchemaConcurrent, BasicDistributedZk2Test, TestFieldResource, TestPhraseSuggestions, JsonLoaderTest, TestSolrDeletionPolicy2, TestSchemaManager, TestQuerySenderListener, AnalysisAfterCoreReloadTest, TermVectorComponentDistributedTest, CoreMergeIndexesAdminHandlerTest, OutputWriterTest, SoftAutoCommitTest, CSVRequestHandlerTest, TestCodecSupport, TestUpdate, HttpPartitionTest, DirectUpdateHandlerTest, TestSolr4Spatial, PingRequestHandlerTest, TestExceedMaxTermLength, TermsComponentTest, TestHashPartitioner, TestManagedSchemaDynamicFieldResource, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, ShardRoutingTest, BasicZkTest, RecoveryZkTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, TestZkChroot, TestDistributedGrouping, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, ZkControllerTest, TestStressVersions, TestRangeQuery, TestCoreContainer, SimpleFacetsTest, SolrCoreTest, StatsComponentTest, SpellCheckComponentTest, TestGroupingSearch, QueryElevationComponentTest, BadIndexSchemaTest, BasicFunctionalityTest, OverseerCollectionProcessorTest, HighlighterTest, CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, TestCoreDiscovery, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, SpatialFilterTest, SuggesterWFSTTest, PolyFieldTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, TestQueryTypes, TestOmitPositions, FileBasedSpellCheckerTest, RequestHandlersTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, IndexSchemaTest, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, MBeansHandlerTest, TestAnalyzedSuggestions, TestBinaryResponseWriter, TestLFUCache, SOLR749Test, UpdateParamsTest, TestSolrIndexConfig, CopyFieldTest, ResponseLogComponentTest, BadComponentTest, TestStressRecovery, TestMergePolicyConfig, NumericFieldsTest, MinimalSchemaTest, TestConfig, TestSolrCoreProperties, DirectSolrConnectionTest, TestLuceneMatchVersion, TestCharFilters, SynonymTokenizerTest, EchoParamsTest, TestSweetSpotSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, PreAnalyzedFieldTest, TestSystemIdResolver, DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, TestSolrJ, TestLRUCache, TestDocumentBuilder, SliceStateTest, SystemInfoHandlerTest, FileUtilsTest, TestTolerantSearch, ActionThrottleTest, AliasIntegrationTest, AssignTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DistribCursorPagingTest, DistributedQueueTest, ExternalCollectionsTest, LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest, MigrateRouteKeyTest, MultiThreadedOCPTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerStatusTest, RemoteQueryErrorTest, RollingRestartTest, SaslZkACLProviderTest, ShardSplitTest, SharedFSAutoReplicaFailoverTest, SolrXmlInZkTest, TestCloudInspectUtil, TestCollectionAPI, TestDistribDocBasedVersion, TestLeaderElectionZkExpiry, TestMiniSolrCloudCluster, TestMiniSolrCloudClusterSSL, TestRebalanceLeaders] [junit4] Completed in 145.67s, 1 test, 1 error <<< FAILURES! [...truncated 239 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:529: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:477: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/build.xml:191: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/solr/common-build.xml:512: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:1368: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-5.x-MacOSX/lucene/common-build.xml:975: There were test failures: 465 suites, 1857 tests, 1 error, 51 ignored (20 assumptions) Total time: 151 minutes 45 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseParallelGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
