Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java8/9/
1 tests failed. FAILED: org.apache.solr.cloud.TestSolrCloudWithKerberos.testKerberizedSolr Error Message: No live SolrServers available to handle this request:[http://127.0.0.1:43820, http://127.0.0.1:45701, http://127.0.0.1:45570, http://127.0.0.1:45919, http://127.0.0.1:41620] Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:43820, http://127.0.0.1:45701, http://127.0.0.1:45570, http://127.0.0.1:45919, http://127.0.0.1:41620] at __randomizedtesting.SeedInfo.seed([9840BAED8B010DDA:33596C2AC160CDED]:0) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:355) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:152) at org.apache.solr.cloud.TestSolrCloudWithKerberos.testKerberizedSolr(TestSolrCloudWithKerberos.java:159) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) 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:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) 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:41620: 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 </title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing /admin/collections. Reason: <pre> org.apache.solr.common.SolrException: Error during request authentication, </pre></p> <hr /><i><small>Powered by Jetty://</small></i> </body> </html> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:529) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) ... 47 more Build Log: [...truncated 10484 lines...] [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithKerberos [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/init-core-data-001 [junit4] 2> 1424189 T8006 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 1427307 T8006 oadsc.DefaultDirectoryService.showSecurityWarnings WARN You didn't change the admin password of directory service instance 'DefaultKrbServer'. Please update the admin password as soon as possible to prevent a possible security breach. [junit4] 2> 1427585 T8006 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 2> 1427586 T8014 oasc.ZkTestServer$2$1.setClientPort client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1427586 T8014 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 1427686 T8006 oasc.ZkTestServer.run start zk server on port:57183 [junit4] 2> 1427687 T8006 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1427688 T8006 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1427688 T8019 oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1427689 T8021 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3e0b0c29 name:ZooKeeperConnection Watcher:127.0.0.1:57183 got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1427689 T8021 oascc.ConnectionManager.process WARN zkClient received AuthFailed [junit4] 2> 1427691 T8021 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3e0b0c29 name:ZooKeeperConnection Watcher:127.0.0.1:57183 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1427691 T8006 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1427691 T8006 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1427691 T8006 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 1427700 T8006 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1427703 T8022 oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1427707 T8006 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1427707 T8024 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14207114 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1427708 T8024 oascc.ConnectionManager.process WARN zkClient received AuthFailed [junit4] 2> 1427707 T8025 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14207114 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1427708 T8006 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1427708 T8006 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1427709 T8006 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 1427710 T8006 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 1427712 T8006 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 1427713 T8006 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 1427715 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1427715 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1427717 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1427718 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 1427719 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1427720 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1427721 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1427722 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 1427723 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1427724 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 1427725 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1427725 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 1427727 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1427727 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1427729 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1427729 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1427731 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1427732 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1427733 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1427733 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1427735 T8006 oasc.AbstractZkTestCase.putConfig put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1427735 T8006 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 1427737 T8006 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1427739 T8026 oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1427740 T8006 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1427743 T8028 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@55bce6c1 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1427743 T8028 oascc.ConnectionManager.process WARN zkClient received AuthFailed [junit4] 2> 1427743 T8029 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@55bce6c1 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1427743 T8006 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1427744 T8006 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1428085 T8006 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1 [junit4] 2> 1428087 T8006 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 1428089 T8006 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@193b859c{/,null,AVAILABLE} [junit4] 2> 1428089 T8006 oejs.AbstractConnector.doStart Started ServerConnector@3d1ae0e4{HTTP/1.1}{127.0.0.1:41620} [junit4] 2> 1428090 T8006 oejs.Server.doStart Started @1430179ms [junit4] 2> 1428090 T8006 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-002/control/data, hostContext=/, hostPort=41620, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores} [junit4] 2> 1428090 T8006 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 1428091 T8006 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/' [junit4] 2> 1428106 T8006 oasc.SolrXmlConfig.fromFile Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/solr.xml [junit4] 2> 1428113 T8006 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores [junit4] 2> 1428113 T8006 oasc.CoreContainer.<init> New CoreContainer 992021330 [junit4] 2> 1428113 T8006 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/] [junit4] 2> 1428114 T8006 oasc.CoreContainer.load loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/lib [junit4] 2> 1428114 T8006 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/lib). [junit4] 2> 1428121 T8006 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1428123 T8006 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1428123 T8006 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1428123 T8006 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1428124 T8006 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1428124 T8006 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57183/solr [junit4] 2> 1428124 T8006 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1428125 T8006 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1428125 T8006 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1428125 T8041 oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1428126 T8043 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32746ce8 name:ZooKeeperConnection Watcher:127.0.0.1:57183 got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1428127 T8044 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@32746ce8 name:ZooKeeperConnection Watcher:127.0.0.1:57183 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1428127 T8006 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1428128 T8006 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1428127 T8043 oascc.ConnectionManager.process WARN zkClient received AuthFailed [junit4] 2> 1428129 T8045 n:127.0.0.1:41620_ oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1428129 T8006 n:127.0.0.1:41620_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1428130 T8047 n:127.0.0.1:41620_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6c41d756 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1428130 T8047 n:127.0.0.1:41620_ oascc.ConnectionManager.process WARN zkClient received AuthFailed [junit4] 2> 1428130 T8048 n:127.0.0.1:41620_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6c41d756 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1428131 T8006 n:127.0.0.1:41620_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1428132 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 1428136 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 1428138 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 1428140 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 1428142 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 1428144 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 1428145 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 1428147 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 1428148 T8006 n:127.0.0.1:41620_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41620_ [junit4] 2> 1428148 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41620_ [junit4] 2> 1428150 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 1428151 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 1428152 T8006 n:127.0.0.1:41620_ oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 1428154 T8006 n:127.0.0.1:41620_ oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:41620_ [junit4] 2> 1428154 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 1428155 T8006 n:127.0.0.1:41620_ oasc.Overseer.start Overseer (id=93882404124884996-127.0.0.1:41620_-n_0000000000) starting [junit4] 2> 1428157 T8006 n:127.0.0.1:41620_ oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 1428162 T8006 n:127.0.0.1:41620_ oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1428163 T8006 n:127.0.0.1:41620_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1428163 T8050 n:127.0.0.1:41620_ oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 1428163 T8049 n:127.0.0.1:41620_ oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 1428166 T8006 n:127.0.0.1:41620_ oasc.CoreContainer.initializeAuthenticationPlugin Authentication plugin class obtained from ZK: org.apache.solr.security.KerberosPlugin [junit4] 2> 1428173 T8006 n:127.0.0.1:41620_ oass.KerberosPlugin.init Params: {token.valid=30, kerberos.principal=HTTP/127.0.0.1, cookie.domain=127.0.0.1, kerberos.keytab=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/keytabs, cookie.path=/, type=kerberos, kerberos.name.rules=DEFAULT} [junit4] 2> 1428185 T8006 n:127.0.0.1:41620_ oasc.CoreContainer.initializeAuthenticationPlugin Reconfiguring the shard handler factory and update shard handler. [junit4] 2> 1428185 T8006 n:127.0.0.1:41620_ oashc.HttpShardHandlerFactory.reconfigureHttpClient Reconfiguring the default client with: org.apache.solr.client.solrj.impl.Krb5HttpClientConfigurer@5ec6ebfa [junit4] 2> 1428185 T8006 n:127.0.0.1:41620_ oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1428188 T8006 n:127.0.0.1:41620_ oasu.UpdateShardHandler.reconfigureHttpClient Reconfiguring the default client with: org.apache.solr.client.solrj.impl.Krb5HttpClientConfigurer@5ec6ebfa [junit4] 2> 1428189 T8006 n:127.0.0.1:41620_ oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1428190 T8006 n:127.0.0.1:41620_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores [junit4] 2> 1428191 T8006 n:127.0.0.1:41620_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1428191 T8006 n:127.0.0.1:41620_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1/ [junit4] 2> 1428192 T8006 n:127.0.0.1:41620_ oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1428192 T8053 n:127.0.0.1:41620_ c:control_collection x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 1428193 T8053 n:127.0.0.1:41620_ c:control_collection x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1428193 T8048 n:127.0.0.1:41620_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1428193 T8053 n:127.0.0.1:41620_ oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1428194 T8049 n:127.0.0.1:41620_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41620";, [junit4] 2> "node_name":"127.0.0.1:41620_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1428195 T8049 n:127.0.0.1:41620_ oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41620";, [junit4] 2> "node_name":"127.0.0.1:41620_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1428195 T8049 n:127.0.0.1:41620_ oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 1428195 T8049 n:127.0.0.1:41620_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 1428196 T8048 n:127.0.0.1:41620_ 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> 1429194 T8053 n:127.0.0.1:41620_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1429194 T8053 n:127.0.0.1:41620_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 1429195 T8053 n:127.0.0.1:41620_ oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1429195 T8053 n:127.0.0.1:41620_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 1429196 T8053 n:127.0.0.1:41620_ oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1429196 T8053 n:127.0.0.1:41620_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1/' [junit4] 2> 1429208 T8053 n:127.0.0.1:41620_ oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 1429222 T8053 n:127.0.0.1:41620_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1429227 T8053 n:127.0.0.1:41620_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1429236 T8053 n:127.0.0.1:41620_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1429238 T8053 n:127.0.0.1:41620_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1429242 T8053 n:127.0.0.1:41620_ oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1429364 T8053 n:127.0.0.1:41620_ oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1429371 T8053 n:127.0.0.1:41620_ oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1429372 T8053 n:127.0.0.1:41620_ oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1429379 T8053 n:127.0.0.1:41620_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1429381 T8053 n:127.0.0.1:41620_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1429383 T8053 n:127.0.0.1:41620_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1429384 T8053 n:127.0.0.1:41620_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1429394 T8053 n:127.0.0.1:41620_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1429395 T8053 n:127.0.0.1:41620_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1429396 T8053 n:127.0.0.1:41620_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1429396 T8053 n:127.0.0.1:41620_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1429396 T8053 n:127.0.0.1:41620_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1429397 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1429397 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1429397 T8053 n:127.0.0.1:41620_ x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@513effe [junit4] 2> 1429398 T8053 n:127.0.0.1:41620_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1/data [junit4] 2> 1429398 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1/data/index/ [junit4] 2> 1429398 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1429398 T8053 n:127.0.0.1:41620_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/control-001/cores/collection1/data/index [junit4] 2> 1429399 T8053 n:127.0.0.1:41620_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=46.45703125, floorSegmentMB=0.359375, forceMergeDeletesPctAllowed=4.1970158864613, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1429399 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c2fe812 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36d9f691),segFN=segments_1,generation=1} [junit4] 2> 1429400 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1429403 T8053 n:127.0.0.1:41620_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1429403 T8053 n:127.0.0.1:41620_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1429403 T8053 n:127.0.0.1:41620_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1429404 T8053 n:127.0.0.1:41620_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1429404 T8053 n:127.0.0.1:41620_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1429404 T8053 n:127.0.0.1:41620_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1429404 T8053 n:127.0.0.1:41620_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1429404 T8053 n:127.0.0.1:41620_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1429405 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1429407 T8053 n:127.0.0.1:41620_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1429408 T8053 n:127.0.0.1:41620_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1429409 T8053 n:127.0.0.1:41620_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1429410 T8053 n:127.0.0.1:41620_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1429412 T8053 n:127.0.0.1:41620_ x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1429413 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1429414 T8053 n:127.0.0.1:41620_ x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1429414 T8053 n:127.0.0.1:41620_ x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1429415 T8053 n:127.0.0.1:41620_ x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1429415 T8053 n:127.0.0.1:41620_ x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1429416 T8053 n:127.0.0.1:41620_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=61.61328125, floorSegmentMB=1.8525390625, forceMergeDeletesPctAllowed=10.319260276732745, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1429416 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4c2fe812 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36d9f691),segFN=segments_1,generation=1} [junit4] 2> 1429417 T8053 n:127.0.0.1:41620_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1429417 T8053 n:127.0.0.1:41620_ x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@91b28d[collection1] main [junit4] 2> 1429417 T8053 n:127.0.0.1:41620_ x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 1429418 T8053 n:127.0.0.1:41620_ x:collection1 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1429418 T8053 n:127.0.0.1:41620_ x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1429419 T8053 n:127.0.0.1:41620_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1429419 T8053 n:127.0.0.1:41620_ x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1429419 T8053 n:127.0.0.1:41620_ x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1429420 T8053 n:127.0.0.1:41620_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 1429420 T8053 n:127.0.0.1:41620_ x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1429420 T8053 n:127.0.0.1:41620_ x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1429420 T8053 n:127.0.0.1:41620_ x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1429421 T8053 n:127.0.0.1:41620_ x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1 [junit4] 2> 1429421 T8054 n:127.0.0.1:41620_ x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@91b28d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1429421 T8053 n:127.0.0.1:41620_ x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field [junit4] 2> 1429422 T8053 n:127.0.0.1:41620_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 1429422 T8053 n:127.0.0.1:41620_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 1429422 T8053 n:127.0.0.1:41620_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Could not find max version in index or recent updates, using new clock 1502118467918102528 [junit4] 2> 1429424 T8053 n:127.0.0.1:41620_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 2 ms to seed version buckets with highest version 1502118467918102528 [junit4] 2> 1429424 T8053 n:127.0.0.1:41620_ x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1429425 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:41620 collection:control_collection shard:shard1 [junit4] 2> 1429426 T8006 n:127.0.0.1:41620_ oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1429426 T8006 n:127.0.0.1:41620_ oass.SolrDispatchFilter.init user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2 [junit4] 2> 1429426 T8006 n:127.0.0.1:41620_ oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1429427 T8006 oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1429428 T8006 oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1429428 T8006 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1429429 T8058 oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1429430 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 1429430 T8006 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1429433 T8060 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f1e0b6 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1429433 T8060 oascc.ConnectionManager.process WARN zkClient received AuthFailed [junit4] 2> 1429434 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 1429435 T8048 n:127.0.0.1:41620_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1429436 T8049 n:127.0.0.1:41620_ 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> 1429436 T8061 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f1e0b6 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1429437 T8006 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1429438 T8006 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1429438 T8006 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1429438 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1429439 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1286 name=collection1 org.apache.solr.core.SolrCore@371c3455 url=http://127.0.0.1:41620/collection1 node=127.0.0.1:41620_ C1286_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:41620, node_name=127.0.0.1:41620_, state=down} [junit4] 2> 1429439 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 C1286 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:41620/collection1/ [junit4] 2> 1429440 T8006 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1429440 T8006 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 1429441 T8006 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1429441 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 C1286 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1429441 T8062 oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1429441 T8006 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1429441 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 C1286 oasc.SyncStrategy.syncToMe http://127.0.0.1:41620/collection1/ has no replicas [junit4] 2> 1429442 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:41620/collection1/ shard1 [junit4] 2> 1429443 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1429445 T8048 n:127.0.0.1:41620_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1429446 T8049 n:127.0.0.1:41620_ 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:41620";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1429448 T8065 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@29f79975 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1429448 T8006 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1429448 T8006 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1429450 T8048 n:127.0.0.1:41620_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1429450 T8049 n:127.0.0.1:41620_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 1 [junit4] 2> 1429450 T8049 n:127.0.0.1:41620_ oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 1429451 T8061 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> 1429451 T8048 n:127.0.0.1:41620_ 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> 1429452 T8064 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@29f79975 name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1429452 T8064 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed [junit4] 2> 1429498 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ZkController.register We are http://127.0.0.1:41620/collection1/ and leader is http://127.0.0.1:41620/collection1/ [junit4] 2> 1429498 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:41620 [junit4] 2> 1429498 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1429499 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 1429499 T8057 n:127.0.0.1:41620_ c:control_collection s:shard1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1429500 T8048 n:127.0.0.1:41620_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1429501 T8049 n:127.0.0.1:41620_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41620";, [junit4] 2> "node_name":"127.0.0.1:41620_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1429501 T8049 n:127.0.0.1:41620_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:41620";, [junit4] 2> "node_name":"127.0.0.1:41620_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1429502 T8049 n:127.0.0.1:41620_ oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json [junit4] 2> 1429503 T8061 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> 1429504 T8048 n:127.0.0.1:41620_ 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> 1429608 T8061 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> 1429608 T8048 n:127.0.0.1:41620_ 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> 1429801 T8006 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1 [junit4] 2> 1429802 T8006 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001 [junit4] 2> 1429803 T8006 oejs.Server.doStart jetty-9.2.10.v20150310 [junit4] 2> 1429805 T8006 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@6c58ada5{/,null,AVAILABLE} [junit4] 2> 1429805 T8006 oejs.AbstractConnector.doStart Started ServerConnector@29768aa3{HTTP/1.1}{127.0.0.1:43820} [junit4] 2> 1429805 T8006 oejs.Server.doStart Started @1431895ms [junit4] 2> 1429806 T8006 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-002/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=43820, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores} [junit4] 2> 1429806 T8006 oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1429807 T8006 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 1429807 T8006 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/' [junit4] 2> 1429821 T8006 oasc.SolrXmlConfig.fromFile Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/solr.xml [junit4] 2> 1429827 T8006 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores [junit4] 2> 1429827 T8006 oasc.CoreContainer.<init> New CoreContainer 1300125204 [junit4] 2> 1429828 T8006 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/] [junit4] 2> 1429828 T8006 oasc.CoreContainer.load loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/lib [junit4] 2> 1429828 T8006 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/lib). [junit4] 2> 1429835 T8006 oashc.HttpShardHandlerFactory.init created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false, [junit4] 2> 1429837 T8006 oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1429837 T8006 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1429838 T8006 oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1429838 T8006 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1429838 T8006 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1429839 T8006 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 1429839 T8006 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57183/solr [junit4] 2> 1429839 T8006 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1429840 T8006 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1429840 T8006 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1429840 T8077 oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1429841 T8079 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7eb5a740 name:ZooKeeperConnection Watcher:127.0.0.1:57183 got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1429841 T8079 oascc.ConnectionManager.process WARN zkClient received AuthFailed [junit4] 2> 1429842 T8079 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7eb5a740 name:ZooKeeperConnection Watcher:127.0.0.1:57183 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1429842 T8006 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1429842 T8006 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1429844 T8006 n:127.0.0.1:43820_ oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1429844 T8080 n:127.0.0.1:43820_ oaz.ClientCnxn$SendThread.startConnect WARN SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it. [junit4] 2> 1429847 T8082 n:127.0.0.1:43820_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2b6e04ab name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:AuthFailed type:None path:null path:null type:None [junit4] 2> 1429847 T8082 n:127.0.0.1:43820_ oascc.ConnectionManager.process WARN zkClient received AuthFailed [junit4] 2> 1429847 T8083 n:127.0.0.1:43820_ oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2b6e04ab name:ZooKeeperConnection Watcher:127.0.0.1:57183/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1429847 T8006 n:127.0.0.1:43820_ oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1429851 T8006 n:127.0.0.1:43820_ oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1430856 T8006 n:127.0.0.1:43820_ oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43820_ [junit4] 2> 1430857 T8006 n:127.0.0.1:43820_ oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43820_ [junit4] 2> 1430860 T8006 n:127.0.0.1:43820_ oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 1430862 T8006 n:127.0.0.1:43820_ oasc.CoreContainer.initializeAuthenticationPlugin Authentication plugin class obtained from ZK: org.apache.solr.security.KerberosPlugin [junit4] 2> 1430862 T8006 n:127.0.0.1:43820_ oass.KerberosPlugin.init Params: {token.valid=30, kerberos.principal=HTTP/127.0.0.1, cookie.domain=127.0.0.1, kerberos.keytab=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/keytabs, cookie.path=/, type=kerberos, kerberos.name.rules=DEFAULT} [junit4] 2> 1430864 T8006 n:127.0.0.1:43820_ oasc.CoreContainer.initializeAuthenticationPlugin Reconfiguring the shard handler factory and update shard handler. [junit4] 2> 1430865 T8006 n:127.0.0.1:43820_ oashc.HttpShardHandlerFactory.reconfigureHttpClient Reconfiguring the default client with: org.apache.solr.client.solrj.impl.Krb5HttpClientConfigurer@370cbbe [junit4] 2> 1430865 T8006 n:127.0.0.1:43820_ oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1430865 T8006 n:127.0.0.1:43820_ oasu.UpdateShardHandler.reconfigureHttpClient Reconfiguring the default client with: org.apache.solr.client.solrj.impl.Krb5HttpClientConfigurer@370cbbe [junit4] 2> 1430866 T8006 n:127.0.0.1:43820_ oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1430867 T8006 n:127.0.0.1:43820_ oasc.CorePropertiesLocator.discover Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores [junit4] 2> 1430868 T8006 n:127.0.0.1:43820_ oasc.CoreDescriptor.<init> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1430868 T8006 n:127.0.0.1:43820_ oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1/ [junit4] 2> 1430868 T8006 n:127.0.0.1:43820_ oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 1430871 T8085 n:127.0.0.1:43820_ c:collection1 x:collection1 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 1430871 T8085 n:127.0.0.1:43820_ c:collection1 x:collection1 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1430872 T8048 n:127.0.0.1:41620_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1430873 T8085 n:127.0.0.1:43820_ oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 1430873 T8085 n:127.0.0.1:43820_ oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 1430873 T8049 n:127.0.0.1:41620_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:43820";, [junit4] 2> "node_name":"127.0.0.1:43820_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 1430874 T8085 n:127.0.0.1:43820_ oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 1430874 T8049 n:127.0.0.1:41620_ oasco.ReplicaMutator.updateState Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:43820";, [junit4] 2> "node_name":"127.0.0.1:43820_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1430874 T8049 n:127.0.0.1:41620_ oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 1430875 T8049 n:127.0.0.1:41620_ oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 1430875 T8085 n:127.0.0.1:43820_ oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 1430875 T8085 n:127.0.0.1:43820_ oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1430977 T8049 n:127.0.0.1:41620_ oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1430978 T8083 n:127.0.0.1:43820_ oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 1430979 T8083 n:127.0.0.1:43820_ oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 1431876 T8085 n:127.0.0.1:43820_ oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1431876 T8085 n:127.0.0.1:43820_ oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 1431877 T8085 n:127.0.0.1:43820_ oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1431877 T8085 n:127.0.0.1:43820_ oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1431878 T8085 n:127.0.0.1:43820_ oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1431878 T8085 n:127.0.0.1:43820_ oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1/' [junit4] 2> 1431890 T8085 n:127.0.0.1:43820_ oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 1431895 T8085 n:127.0.0.1:43820_ oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 1431899 T8085 n:127.0.0.1:43820_ oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1431920 T8085 n:127.0.0.1:43820_ oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1431921 T8085 n:127.0.0.1:43820_ oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1431925 T8085 n:127.0.0.1:43820_ oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1432023 T8085 n:127.0.0.1:43820_ oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1432029 T8085 n:127.0.0.1:43820_ oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1432030 T8085 n:127.0.0.1:43820_ oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1432038 T8085 n:127.0.0.1:43820_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1432041 T8085 n:127.0.0.1:43820_ oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1432043 T8085 n:127.0.0.1:43820_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1432043 T8085 n:127.0.0.1:43820_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1432044 T8085 n:127.0.0.1:43820_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1432044 T8085 n:127.0.0.1:43820_ oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1432045 T8085 n:127.0.0.1:43820_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 1432045 T8085 n:127.0.0.1:43820_ oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 1432045 T8085 n:127.0.0.1:43820_ oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1432045 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1432045 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1/], dataDir=[null] [junit4] 2> 1432046 T8085 n:127.0.0.1:43820_ x:collection1 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@513effe [junit4] 2> 1432046 T8085 n:127.0.0.1:43820_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1/data [junit4] 2> 1432046 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 1432047 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1432047 T8085 n:127.0.0.1:43820_ x:collection1 oasc.CachingDirectoryFactory.get return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/shard-1-001/cores/collection1/data/index [junit4] 2> 1432047 T8085 n:127.0.0.1:43820_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=46.45703125, floorSegmentMB=0.359375, forceMergeDeletesPctAllowed=4.1970158864613, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1432048 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4f84f3ea lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4fb3db81),segFN=segments_1,generation=1} [junit4] 2> 1432048 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1432052 T8085 n:127.0.0.1:43820_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1432052 T8085 n:127.0.0.1:43820_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1432052 T8085 n:127.0.0.1:43820_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1432052 T8085 n:127.0.0.1:43820_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1432053 T8085 n:127.0.0.1:43820_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1432053 T8085 n:127.0.0.1:43820_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1432053 T8085 n:127.0.0.1:43820_ x:collection1 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1432053 T8085 n:127.0.0.1:43820_ x:collection1 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1432053 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1432055 T8085 n:127.0.0.1:43820_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1432056 T8085 n:127.0.0.1:43820_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1432057 T8085 n:127.0.0.1:43820_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1432058 T8085 n:127.0.0.1:43820_ x:collection1 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1432062 T8085 n:127.0.0.1:43820_ x:collection1 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1432062 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 1432063 T8085 n:127.0.0.1:43820_ x:collection1 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1432063 T8085 n:127.0.0.1:43820_ x:collection1 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1432073 T8085 n:127.0.0.1:43820_ x:collection1 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1432074 T8085 n:127.0.0.1:43820_ x:collection1 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1432074 T8085 n:127.0.0.1:43820_ x:collection1 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=61.61328125, floorSegmentMB=1.8525390625, forceMergeDeletesPctAllowed=10.319260276732745, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1432075 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4f84f3ea lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4fb3db81),segFN=segments_1,generation=1} [junit4] 2> 1432075 T8085 n:127.0.0.1:43820_ x:collection1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1432075 T8085 n:127.0.0.1:43820_ x:collection1 oass.SolrIndexSearcher.<init> Opening Searcher@444ca24[collection1] main [junit4] 2> 1432075 T8085 n:127.0.0.1:43820_ x:collection1 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1432079 T8085 n:127.0.0.1:43820_ x:collection1 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1432080 T8085 n:127.0.0.1:43820_ x:collection1 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1432080 T8085 n:127.0.0.1:43820_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1432080 T8085 n:127.0.0.1:43820_ x:collection1 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1432080 T8085 n:127.0.0.1:43820_ x:collection1 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1432081 T8085 n:127.0.0.1:43820_ x:collection1 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 1432081 T8085 n:127.0.0.1:43820_ x:collection1 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1432081 T8085 n:127.0.0.1:43820_ x:collection1 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1432081 T8085 n:127.0.0.1:43820_ x:collection1 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1432082 T8086 n:127.0.0.1:43820_ x:collection1 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@444ca24[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1432082 T8085 n:127.0.0.1:43820_ x:collection1 oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1 [junit4] 2> 1432083 T8085 n:127.0.0.1:43820_ x:collection1 oasu.UpdateLog.onFirstSearcher On first searcher opened, looking up max value of version field [junit4] 2> 1432083 T8085 n:127.0.0.1:43820_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 1432083 T8085 n:127.0.0.1:43820_ x:collection1 oasu.VersionInfo.getMaxVersionFromIndex No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 1432083 T8085 n:127.0.0.1:43820_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Could not find max version in index or recent updates, using new clock 1502118470708363264 [junit4] 2> 1432086 T8085 n:127.0.0.1:43820_ x:collection1 oasu.UpdateLog.seedBucketsWithHighestVersion Took 3 ms to seed version buckets with highest version 1502118470708363264 [junit4] 2> 1432086 T8085 n:127.0.0.1:43820_ x:collection1 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1432087 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:43820 collection:collection1 shard:shard2 [junit4] 2> 1432088 T8006 n:127.0.0.1:43820_ oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1432088 T8006 n:127.0.0.1:43820_ oass.SolrDispatchFilter.init user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2 [junit4] 2> 1432088 T8006 n:127.0.0.1:43820_ oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1432089 T8006 oascsi.Krb5HttpClientConfigurer.configure Setting up SPNego auth with config: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001/tempDir-001/minikdc/jaas-client.conf [junit4] 2> 1432092 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 1432096 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 1432097 T8048 n:127.0.0.1:41620_ oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1432097 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1432097 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C1287 name=collection1 org.apache.solr.core.SolrCore@aaa5bd9 url=http://127.0.0.1:43820/collection1 node=127.0.0.1:43820_ C1287_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:43820, node_name=127.0.0.1:43820_, state=down} [junit4] 2> 1432098 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 C1287 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:43820/collection1/ [junit4] 2> 1432098 T8049 n:127.0.0.1:41620_ oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 1432098 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 C1287 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1432098 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 C1287 oasc.SyncStrategy.syncToMe http://127.0.0.1:43820/collection1/ has no replicas [junit4] 2> 1432098 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:43820/collection1/ shard2 [junit4] 2> 1432099 T8089 n:127.0.0.1:43820_ c:collection1 s:shard2 x:collection1 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 1432099 T8049 n:127.0.0.1:41620_ oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 1432099 T8083 n:127.0.0.1:43820_ oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 1432101 T8083 n:127.0.0 [...truncated too long message...] jsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@6649544c{/,null,UNAVAILABLE} [junit4] 2> 1448592 T8006 c:control_collection s:shard1 x:collection1 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:57183 57183 [junit4] 2> 1448774 T8014 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:57183 57183 [junit4] 2> 1448775 T8014 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 7 /solr/aliases.json [junit4] 2> 7 /solr/clusterstate.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 7 /solr/live_nodes [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> 4 /solr/overseer/queue [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithKerberos -Dtests.method=testKerberizedSolr -Dtests.seed=9840BAED8B010DDA -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ar_TN -Dtests.timezone=Atlantic/Reykjavik -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 24.6s J2 | TestSolrCloudWithKerberos.testKerberizedSolr <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:43820, http://127.0.0.1:45701, http://127.0.0.1:45570, http://127.0.0.1:45919, http://127.0.0.1:41620] [junit4] > at __randomizedtesting.SeedInfo.seed([9840BAED8B010DDA:33596C2AC160CDED]:0) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:355) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:152) [junit4] > at org.apache.solr.cloud.TestSolrCloudWithKerberos.testKerberizedSolr(TestSolrCloudWithKerberos.java:159) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:41620: Expected mime type application/octet-stream but got text/html. <html> [junit4] > <head> [junit4] > <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> [junit4] > <title>Error 500 </title> [junit4] > </head> [junit4] > <body> [junit4] > <h2>HTTP ERROR: 500</h2> [junit4] > <p>Problem accessing /admin/collections. Reason: [junit4] > <pre> org.apache.solr.common.SolrException: Error during request authentication, </pre></p> [junit4] > <hr /><i><small>Powered by Jetty://</small></i> [junit4] > </body> [junit4] > </html> [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:529) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) [junit4] > ... 47 more [junit4] 2> 1448785 T8006 c:control_collection s:shard1 x:collection1 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithKerberos 9840BAED8B010DDA-001 [junit4] 2> 24601 T8005 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50), sim=DefaultSimilarity, locale=ar_TN, timezone=Atlantic/Reykjavik [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=4,threads=1,free=160314800,total=494927872 [junit4] 2> NOTE: All tests run in this JVM: [TestCloudInspectUtil, TestTrie, OverseerCollectionProcessorTest, CachingDirectoryFactoryTest, TestNoOpRegenerator, DocValuesMissingTest, OpenExchangeRatesOrgProviderTest, SuggesterTest, DirectUpdateHandlerOptimizeTest, TestClusterStateMutator, CursorPagingTest, TestInitParams, HardAutoCommitTest, TestFastOutputStream, TestRawResponseWriter, TestManagedSchemaFieldResource, TestPartialUpdateDeduplication, SolrIndexSplitterTest, TestStressRecovery, AsyncMigrateRouteKeyTest, TestFieldCollectionResource, PrimUtilsTest, ActionThrottleTest, TestExactSharedStatsCache, ResponseLogComponentTest, HdfsRecoverLeaseTest, DeleteShardTest, XmlUpdateRequestHandlerTest, TestSurroundQueryParser, TestSchemaSimilarityResource, TestStandardQParsers, TestQueryUtils, CdcrUpdateLogTest, TestReload, TestMiniSolrCloudClusterSSL, TestUtils, TestReplicationHandlerBackup, MergeStrategyTest, RankQueryTest, SimpleCollectionCreateDeleteTest, TestLuceneMatchVersion, TestQuerySenderNoQuery, TestManagedStopFilterFactory, DistributedQueryElevationComponentTest, BinaryUpdateRequestHandlerTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestReqParamsAPI, TestMacros, PathHierarchyTokenizerFactoryTest, TermVectorComponentDistributedTest, DistributedIntervalFacetingTest, TestRestManager, BasicDistributedZk2Test, DistributedFacetPivotWhiteBoxTest, TestSearcherReuse, FileBasedSpellCheckerTest, DOMUtilTest, OverseerTest, XsltUpdateRequestHandlerTest, TestSolrConfigHandler, TestSolrXml, TestObjectReleaseTracker, TestDefaultSimilarityFactory, TestReRankQParserPlugin, SoftAutoCommitTest, TestMaxScoreQueryParser, RecoveryZkTest, QueryEqualityTest, TestCollationField, FacetPivotSmallTest, CircularListTest, TestNRTOpen, SolrInfoMBeanTest, TestIntervalFaceting, MultiThreadedOCPTest, FullSolrCloudDistribCmdsTest, SimpleFacetsTest, TestCSVResponseWriter, UnloadDistributedZkTest, BasicZkTest, TestRandomFaceting, LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, AutoCommitTest, TestRecovery, TestHashPartitioner, ZkControllerTest, TestStressReorder, TestJoin, TestStressVersions, DistributedTermsComponentTest, TestRangeQuery, SolrCoreTest, StatsComponentTest, SpellCheckComponentTest, TestGroupingSearch, BadIndexSchemaTest, TestFiltering, DirectUpdateHandlerTest, ShowFileRequestHandlerTest, SimplePostToolTest, TestExtendedDismaxParser, SuggesterFSTTest, DocValuesTest, SuggesterWFSTTest, WordBreakSolrSpellCheckerTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, StandardRequestHandlerTest, PrimitiveFieldTypeTest, TermsComponentTest, TestIndexingPerformance, MoreLikeThisHandlerTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, IndexSchemaTest, JSONWriterTest, TestAnalyzedSuggestions, CSVRequestHandlerTest, TestBinaryResponseWriter, SOLR749Test, CopyFieldTest, TestMergePolicyConfig, TestDocSet, NumericFieldsTest, TestSolrCoreProperties, TestPhraseSuggestions, TestCharFilters, TestCodecSupport, TestXIncludeConfig, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestFastWriter, ResourceLoaderTest, ChaosMonkeyNothingIsSafeTest, CloudExitableDirectoryReaderTest, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, DistribDocExpirationUpdateProcessorTest, LeaderInitiatedRecoveryOnCommitTest, ReplicationFactorTest, SSLMigrationTest, SaslZkACLProviderTest, TestLeaderElectionZkExpiry, TestMiniSolrCloudCluster, TestReplicaProperties, TestShortCircuitedRequests, TestSolrCloudWithKerberos] [junit4] Completed [417/502] on J2 in 25.37s, 1 test, 1 error <<< FAILURES! [...truncated 275 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:526: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:474: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/build.xml:61: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/extra-targets.xml:39: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/build.xml:229: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/solr/common-build.xml:512: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/common-build.xml:1415: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java8/lucene/common-build.xml:973: There were test failures: 502 suites, 1984 tests, 1 error, 35 ignored (25 assumptions) Total time: 49 minutes 36 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
