You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/10 08:32:28 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 3596
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3596/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.TestSolrCloudWithKerberosAlt.testBasics
Error Message:
Could not find collection:testkerberoscollection
Stack Trace:
java.lang.AssertionError: Could not find collection:testkerberoscollection
at __randomizedtesting.SeedInfo.seed([4E6CEC3CD492BFE4:73B44210EC7CE194]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:153)
at org.apache.solr.cloud.TestSolrCloudWithKerberosAlt.testCollectionCreateSearchDelete(TestSolrCloudWithKerberosAlt.java:206)
at org.apache.solr.cloud.TestSolrCloudWithKerberosAlt.testBasics(TestSolrCloudWithKerberosAlt.java:152)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11302 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithKerberosAlt
[junit4] 2> 1199377 WARN (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.d.s.c.DefaultDirectoryService 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> 1201634 INFO (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.s.c.TestSolrCloudWithKerberosAlt Enable delegation token: true
[junit4] 2> 1201635 INFO (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-002
[junit4] 2> 1201635 INFO (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1201636 INFO (Thread-1601) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1201636 INFO (Thread-1601) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1201740 INFO (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.s.c.ZkTestServer start zk server on port:52023
[junit4] 2> 1201741 WARN (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]-SendThread(127.0.0.1:52023)) [ ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1201742 WARN (zkCallback-1340-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ec21ae name: ZooKeeperConnection Watcher:127.0.0.1:52023 got event WatchedEvent state:AuthFailed type:None path:null path: null type: None
[junit4] 2> 1201742 WARN (zkCallback-1340-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed
[junit4] 2> 1201871 INFO (jetty-launcher-1339-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1201872 INFO (jetty-launcher-1339-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3c9af2cf{/solr,null,AVAILABLE}
[junit4] 2> 1201873 INFO (jetty-launcher-1339-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@2f757efd{HTTP/1.1,[http/1.1]}{127.0.0.1:52025}
[junit4] 2> 1201873 INFO (jetty-launcher-1339-thread-1) [ ] o.e.j.s.Server Started @1209950ms
[junit4] 2> 1201873 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52025}
[junit4] 2> 1201874 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1201874 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1201874 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1201874 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-10T07:21:09.907Z
[junit4] 2> 1201875 WARN (jetty-launcher-1339-thread-1-SendThread(127.0.0.1:52023)) [ ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1201876 WARN (zkCallback-1341-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69053c5a name: ZooKeeperConnection Watcher:127.0.0.1:52023/solr got event WatchedEvent state:AuthFailed type:None path:null path: null type: None
[junit4] 2> 1201876 WARN (zkCallback-1341-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed
[junit4] 2> 1201892 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1201909 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52023/solr
[junit4] 2> 1201910 WARN (jetty-launcher-1339-thread-1-SendThread(127.0.0.1:52023)) [ ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1201911 WARN (zkCallback-1344-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@492efb68 name: ZooKeeperConnection Watcher:127.0.0.1:52023 got event WatchedEvent state:AuthFailed type:None path:null path: null type: None
[junit4] 2> 1201912 WARN (zkCallback-1344-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed
[junit4] 2> 1201947 WARN (jetty-launcher-1339-thread-1-SendThread(127.0.0.1:52023)) [n:127.0.0.1:52025_solr ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1201948 WARN (zkCallback-1345-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7672ee7d name: ZooKeeperConnection Watcher:127.0.0.1:52023/solr got event WatchedEvent state:AuthFailed type:None path:null path: null type: None
[junit4] 2> 1201948 WARN (zkCallback-1345-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed
[junit4] 2> 1202227 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52025_solr
[junit4] 2> 1202240 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.s.c.Overseer Overseer (id=96736645590089731-127.0.0.1:52025_solr-n_0000000000) starting
[junit4] 2> 1202374 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52025_solr
[junit4] 2> 1202387 INFO (zkCallback-1345-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1202393 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.KerberosPlugin
[junit4] 2> 1202410 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder Setting up SPNego auth with config: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf
[junit4] 2> 1202473 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.s.s.KerberosPlugin Params: {token.valid=30, kerberos.principal=HTTP/127.0.0.1, kerberos.keytab=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/keytabs, cookie.domain=127.0.0.1, token.validity=36000, type=kerberos, delegation-token.token-kind=solr-dt, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, zk-dt-secret-manager.enable=true, kerberos.name.rules=RULE:[1:$1@$0](.*EXAMPLE.COM)s/@.*//
[junit4] 2> RULE:[2:$2@$0](.*EXAMPLE.COM)s/@.*//
[junit4] 2> DEFAULT, signer.secret.provider=zookeeper}
[junit4] 2> 1202790 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 1202807 WARN (jetty-launcher-1339-thread-1-SendThread(127.0.0.1:52023)) [n:127.0.0.1:52025_solr ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1202828 ERROR (jetty-launcher-1339-thread-1-EventThread) [n:127.0.0.1:52025_solr ] o.a.c.ConnectionState Authentication failed
[junit4] 2> 1202866 INFO (jetty-launcher-1339-thread-1-EventThread) [n:127.0.0.1:52025_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 1203656 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder Setting up SPNego auth with config: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf
[junit4] 2> 1203660 INFO (jetty-launcher-1339-thread-1) [n:127.0.0.1:52025_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-002/node1/.
[junit4] 2> 1203663 WARN (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]-SendThread(127.0.0.1:52023)) [ ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1203669 WARN (zkCallback-1347-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@476c683 name: ZooKeeperConnection Watcher:127.0.0.1:52023 got event WatchedEvent state:AuthFailed type:None path:null path: null type: None
[junit4] 2> 1203669 WARN (zkCallback-1347-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed
[junit4] 2> 1203721 WARN (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]-SendThread(127.0.0.1:52023)) [ ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1203722 WARN (zkCallback-1349-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@22681db0 name: ZooKeeperConnection Watcher:127.0.0.1:52023/solr got event WatchedEvent state:AuthFailed type:None path:null path: null type: None
[junit4] 2> 1203722 WARN (zkCallback-1349-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed
[junit4] 2> 1209236 WARN (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]-SendThread(127.0.0.1:52023)) [ ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1209237 WARN (zkCallback-1351-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b2c2637 name: ZooKeeperConnection Watcher:127.0.0.1:52023/solr got event WatchedEvent state:AuthFailed type:None path:null path: null type: None
[junit4] 2> 1209237 WARN (zkCallback-1351-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed
[junit4] 2> 1209248 INFO (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1209311 WARN (qtp996885049-7337) [ ] o.a.h.s.a.s.KerberosAuthenticationHandler 'Authorization' does not start with 'Negotiate' : Basic aGFycnk6SGFycnlJc1ViZXJDb29s
[junit4] 1> Debug is true storeKey true useTicketCache false useKeyTab true doNotPrompt false ticketCache is null isInitiator true KeyTab is /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/keytabs refreshKrb5Config is true principal is solr tryFirstPass is false useFirstPass is false storePass is false clearPass is false
[junit4] 1> Refreshing Kerberos configuration
[junit4] 1> principal is solr@EXAMPLE.COM
[junit4] 1> Will use keytab
[junit4] 1> Commit Succeeded
[junit4] 1>
[junit4] 2> 1211256 INFO (qtp996885049-7338) [n:127.0.0.1:52025_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&property.solr.tests.ramBufferSizeMB=100&property.solr.tests.mergePolicyFactory=org.apache.solr.index.TieredMergePolicyFactory&property.solr.tests.useMergePolicyFactory=true&property.solr.tests.useMergePolicy=false&version=2&property.solr.tests.mergeScheduler=org.apache.lucene.index.ConcurrentMergeScheduler&property.config=solrconfig-tlog.xml&property.solr.tests.maxBufferedDocs=100000&name=testkerberoscollection&action=CREATE&numShards=1&property.solr.directoryFactory=solr.RAMDirectoryFactory&wt=javabin and sendToOCPQueue=true
[junit4] 2> 1211270 INFO (OverseerThreadFactory-3456-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.CreateCollectionCmd Create collection testkerberoscollection
[junit4] 2> 1211270 INFO (OverseerThreadFactory-3456-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.CreateCollectionCmd Only one config set found in zk - using it:solrCloudCollectionConfig
[junit4] 2> 1211399 WARN (qtp996885049-7340) [ ] o.a.h.s.a.s.KerberosAuthenticationHandler 'Authorization' does not start with 'Negotiate' : Basic aGFycnk6SGFycnlJc1ViZXJDb29s
[junit4] 2> 1211400 ERROR (OverseerThreadFactory-3456-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.OverseerCollectionMessageHandler Error from shard: http://127.0.0.1:52025/solr
[junit4] 2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:52025/solr: Expected mime type application/octet-stream but got text/html. <html>
[junit4] 2> <head>
[junit4] 2> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
[junit4] 2> <title>Error 401 </title>
[junit4] 2> </head>
[junit4] 2> <body>
[junit4] 2> <h2>HTTP ERROR: 401</h2>
[junit4] 2> <p>Problem accessing /solr/admin/cores. Reason:
[junit4] 2> <pre> Authentication required</pre></p>
[junit4] 2> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.8.v20160314</a><hr/>
[junit4] 2> </body>
[junit4] 2> </html>
[junit4] 2>
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:578)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251)
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:195)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1211401 ERROR (OverseerThreadFactory-3456-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.OverseerCollectionMessageHandler Cleaning up collection [testkerberoscollection].
[junit4] 2> 1211401 INFO (OverseerThreadFactory-3456-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1212143 INFO (OverseerThreadFactory-3456-thread-1-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.CreateCollectionCmd Cleaned up artifacts for failed create collection for [testkerberoscollection]
[junit4] 2> 1212396 INFO (qtp996885049-7338) [n:127.0.0.1:52025_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 1213294 INFO (OverseerCollectionConfigSetProcessor-96736645590089731-127.0.0.1:52025_solr-n_0000000000) [n:127.0.0.1:52025_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1242569 ERROR (qtp996885049-7338) [n:127.0.0.1:52025_solr ] o.a.s.h.a.CollectionsHandler Timed out waiting for new collection's replicas to become ACTIVE with timeout=30
[junit4] 2> 1242570 INFO (qtp996885049-7338) [n:127.0.0.1:52025_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&property.solr.tests.ramBufferSizeMB=100&property.solr.tests.mergePolicyFactory=org.apache.solr.index.TieredMergePolicyFactory&property.solr.tests.useMergePolicyFactory=true&property.solr.tests.useMergePolicy=false&version=2&property.solr.tests.mergeScheduler=org.apache.lucene.index.ConcurrentMergeScheduler&property.config=solrconfig-tlog.xml&property.solr.tests.maxBufferedDocs=100000&name=testkerberoscollection&action=CREATE&numShards=1&property.solr.directoryFactory=solr.RAMDirectoryFactory&wt=javabin} status=0 QTime=31313
[junit4] 2> 1242588 WARN (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=solr&p=solr@EXAMPLE.COM&t=kerberos-dt&e=1476120079073&s=0u5a3E40oWIy+4oRqZvWB0Bk+I8="; Path=/; Domain=127.0.0.1; Expires=Mo, 10-Okt-2016 17:21:19 GMT; HttpOnly". Invalid 'expires' attribute: Mo, 10-Okt-2016 17:21:19 GMT
[junit4] 2> 1242589 WARN (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]-SendThread(127.0.0.1:52023)) [ ] o.a.z.ClientCnxn SASL configuration failed: javax.security.auth.login.LoginException: No JAAS configuration section named 'Client' was found in specified JAAS configuration file: '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001/tempDir-001/minikdc/jaas-client.conf'. Will continue connection to Zookeeper server without SASL authentication, if Zookeeper server allows it.
[junit4] 2> 1242590 WARN (zkCallback-1352-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e37cfc8 name: ZooKeeperConnection Watcher:127.0.0.1:52023/solr got event WatchedEvent state:AuthFailed type:None path:null path: null type: None
[junit4] 2> 1242590 WARN (zkCallback-1352-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed
[junit4] 2> 1242609 INFO (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1242611 INFO (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: testkerberoscollection failOnTimeout:true timeout (sec):330
[junit4] 1> -
[junit4] 2> 1242656 INFO (jetty-launcher-1339-thread-1) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@2f757efd{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1242657 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2074323711
[junit4] 2> 1242689 INFO (jetty-launcher-1339-thread-1) [ ] o.a.s.c.Overseer Overseer (id=96736645590089731-127.0.0.1:52025_solr-n_0000000000) closing
[junit4] 2> 1242689 INFO (OverseerStateUpdate-96736645590089731-127.0.0.1:52025_solr-n_0000000000) [n:127.0.0.1:52025_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52025_solr
[junit4] 2> 1244202 WARN (zkCallback-1345-thread-3-processing-n:127.0.0.1:52025_solr) [n:127.0.0.1:52025_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1244203 ERROR (Thread[Thread-1610,5,TGRP-TestSolrCloudWithKerberosAlt]) [n:127.0.0.1:52025_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 1244265 INFO (jetty-launcher-1339-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3c9af2cf{/solr,null,UNAVAILABLE}
[junit4] 2> 1244267 INFO (TEST-TestSolrCloudWithKerberosAlt.testBasics-seed#[4E6CEC3CD492BFE4]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52023 52023
[junit4] 2> 1244382 INFO (Thread-1601) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52023 52023
[junit4] 2> 1252137 WARN (Thread-1601) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 39 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithKerberosAlt -Dtests.method=testBasics -Dtests.seed=4E6CEC3CD492BFE4 -Dtests.slow=true -Dtests.locale=de-AT -Dtests.timezone=Asia/Chongqing -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 72.4s J1 | TestSolrCloudWithKerberosAlt.testBasics <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not find collection:testkerberoscollection
[junit4] > at __randomizedtesting.SeedInfo.seed([4E6CEC3CD492BFE4:73B44210EC7CE194]:0)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:153)
[junit4] > at org.apache.solr.cloud.TestSolrCloudWithKerberosAlt.testCollectionCreateSearchDelete(TestSolrCloudWithKerberosAlt.java:206)
[junit4] > at org.apache.solr.cloud.TestSolrCloudWithKerberosAlt.testBasics(TestSolrCloudWithKerberosAlt.java:152)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithKerberosAlt_4E6CEC3CD492BFE4-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1604, maxMBSortInHeap=7.4267036305939405, sim=ClassicSimilarity, locale=de-AT, timezone=Asia/Chongqing
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=100297008,total=334483456
[junit4] 2> NOTE: All tests run in this JVM: [SolrCloudExampleTest, TestManagedSchemaAPI, SpellingQueryConverterTest, CloudMLTQParserTest, BlockDirectoryTest, ReplicationFactorTest, BJQParserTest, TestClassicSimilarityFactory, TestSchemaManager, SolrCoreCheckLockOnStartupTest, SignatureUpdateProcessorFactoryTest, TestUseDocValuesAsStored2, TestFieldSortValues, TestConfigSetsAPIZkFailure, CircularListTest, GraphQueryTest, TestMaxScoreQueryParser, TestQuerySenderListener, JSONWriterTest, TestFileDictionaryLookup, SolrInfoMBeanTest, TestLeaderInitiatedRecoveryThread, TestDistributedMissingSort, EnumFieldTest, AddBlockUpdateTest, HdfsRecoveryZkTest, IndexSchemaTest, TestDocSet, TestSystemIdResolver, AliasIntegrationTest, TestSolrFieldCacheMBean, TestMacroExpander, TestHdfsUpdateLog, TestSolrCoreProperties, SolrIndexSplitterTest, TestAuthorizationFramework, TestStressUserVersions, TestSmileRequest, TestDistribDocBasedVersion, DirectoryFactoryTest, ParsingFieldUpdateProcessorsTest, ConnectionReuseTest, TestXmlQParser, StatsReloadRaceTest, FileBasedSpellCheckerTest, OverseerStatusTest, SpellCheckCollatorTest, TestDynamicFieldResource, TestDistributedGrouping, AnalyticsQueryTest, TestZkChroot, BooleanFieldTest, TestDefaultSearchFieldResource, TestPHPSerializedResponseWriter, TestCodecSupport, SecurityConfHandlerTest, DistributedVersionInfoTest, TestJoin, HdfsNNFailoverTest, TestEmbeddedSolrServerConstructors, TestSchemaResource, QueryParsingTest, FacetPivotSmallTest, TestCollationFieldDocValues, CdcrReplicationHandlerTest, TestSolrQueryParser, FullHLLTest, OverriddenZkACLAndCredentialsProvidersTest, TestConfigSetsAPI, TestSubQueryTransformerCrossCore, TestBadConfig, TestOrdValues, BadComponentTest, TestSizeLimitedDistributedMap, TestSlowCompositeReaderWrapper, NotRequiredUniqueKeyTest, DistributedTermsComponentTest, DistribCursorPagingTest, NoCacheHeaderTest, EchoParamsTest, SortSpecParsingTest, CleanupOldIndexTest, HdfsChaosMonkeySafeLeaderTest, SpellPossibilityIteratorTest, BigEndianAscendingWordDeserializerTest, TestGraphTermsQParserPlugin, TestQueryUtils, UUIDFieldTest, TestMiniSolrCloudClusterSSL, TestBinaryResponseWriter, TestInitQParser, TestFoldingMultitermQuery, TestRecoveryHdfs, TestAuthenticationFramework, DistributedFacetPivotLargeTest, TestFuzzyAnalyzedSuggestions, RequestLoggingTest, TestReplicationHandlerBackup, ShardRoutingCustomTest, TestOmitPositions, TestObjectReleaseTracker, TestReversedWildcardFilterFactory, DeleteShardTest, DefaultValueUpdateProcessorTest, BasicAuthIntegrationTest, DirectUpdateHandlerOptimizeTest, TestRandomFlRTGCloud, TestTolerantUpdateProcessorCloud, WordBreakSolrSpellCheckerTest, MergeStrategyTest, TestDistributedStatsComponentCardinality, ClusterStateTest, TestQueryWrapperFilter, TestRebalanceLeaders, DocExpirationUpdateProcessorFactoryTest, CurrencyFieldOpenExchangeTest, TestRecovery, PreAnalyzedFieldTest, DebugComponentTest, RollingRestartTest, ClassificationUpdateProcessorFactoryTest, HdfsBasicDistributedZkTest, RemoteQueryErrorTest, TestBackupRepositoryFactory, TestAnalyzedSuggestions, HighlighterConfigTest, BitVectorTest, TestSolrXml, ConfigSetsAPITest, TestNoOpRegenerator, TestSolrCloudWithKerberosAlt]
[junit4] Completed [247/635 (1!)] on J1 in 72.42s, 1 test, 1 failure <<< FAILURES!
[...truncated 61624 lines...]