Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6374/ Java: 64bit/jdk1.8.0_121 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail Error Message: expected:<200> but was:<404> Stack Trace: java.lang.AssertionError: expected:<200> but was:<404> at __randomizedtesting.SeedInfo.seed([A99994290E403D27:C126A103DEDA2FCB]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:140) at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:294) 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:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) 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:368) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11805 lines...] [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A99994290E403D27-001\init-core-data-001 [junit4] 2> 1241769 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A99994290E403D27]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 1241775 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A99994290E403D27]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 1241784 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A99994290E403D27]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A99994290E403D27-001\tempDir-001 [junit4] 2> 1241784 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A99994290E403D27]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1241798 INFO (Thread-3275) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1241798 INFO (Thread-3275) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1241886 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A99994290E403D27]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:55879 [junit4] 2> 1241905 INFO (jetty-launcher-1835-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1241906 INFO (jetty-launcher-1835-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@32617d6{/solr,null,AVAILABLE} [junit4] 2> 1241908 INFO (jetty-launcher-1835-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@84a8521{SSL,[ssl, http/1.1]}{127.0.0.1:55883} [junit4] 2> 1241908 INFO (jetty-launcher-1835-thread-1) [ ] o.e.j.s.Server Started @1249042ms [junit4] 2> 1241908 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55883} [junit4] 2> 1241908 ERROR (jetty-launcher-1835-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1241908 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 1241908 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1241909 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1241909 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-29T03:54:36.345Z [junit4] 2> 1241920 INFO (jetty-launcher-1835-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1241928 INFO (jetty-launcher-1835-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d166e96{/solr,null,AVAILABLE} [junit4] 2> 1241929 INFO (jetty-launcher-1835-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@12541795{SSL,[ssl, http/1.1]}{127.0.0.1:55888} [junit4] 2> 1241929 INFO (jetty-launcher-1835-thread-2) [ ] o.e.j.s.Server Started @1249064ms [junit4] 2> 1241929 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55888} [junit4] 2> 1241930 ERROR (jetty-launcher-1835-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1241930 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 1241930 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1241930 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1241930 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-29T03:54:36.366Z [junit4] 2> 1241970 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1241971 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1241984 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55879/solr [junit4] 2> 1241985 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55879/solr [junit4] 2> 1242167 INFO (jetty-launcher-1835-thread-1) [n:127.0.0.1:55883_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55883_solr [junit4] 2> 1242168 INFO (jetty-launcher-1835-thread-2) [n:127.0.0.1:55888_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55888_solr [junit4] 2> 1242169 INFO (jetty-launcher-1835-thread-1) [n:127.0.0.1:55883_solr ] o.a.s.c.Overseer Overseer (id=97364349828333573-127.0.0.1:55883_solr-n_0000000000) starting [junit4] 2> 1242199 INFO (jetty-launcher-1835-thread-2) [n:127.0.0.1:55888_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin [junit4] 2> 1242204 INFO (zkCallback-1846-thread-1-processing-n:127.0.0.1:55888_solr) [n:127.0.0.1:55888_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1242204 INFO (zkCallback-1845-thread-1-processing-n:127.0.0.1:55883_solr) [n:127.0.0.1:55883_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1242286 INFO (jetty-launcher-1835-thread-2) [n:127.0.0.1:55888_solr ] o.a.s.s.KerberosPlugin Params: {token.valid=30, delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, token.validity=36000, signer.secret.provider.zookeeper.path=/token, type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper} [junit4] 2> 1242395 INFO (jetty-launcher-1835-thread-1) [n:127.0.0.1:55883_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55883_solr [junit4] 2> 1242434 INFO (jetty-launcher-1835-thread-1) [n:127.0.0.1:55883_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin [junit4] 2> 1242435 INFO (zkCallback-1846-thread-1-processing-n:127.0.0.1:55888_solr) [n:127.0.0.1:55888_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1242435 INFO (zkCallback-1845-thread-1-processing-n:127.0.0.1:55883_solr) [n:127.0.0.1:55883_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1242558 INFO (jetty-launcher-1835-thread-1) [n:127.0.0.1:55883_solr ] o.a.s.s.KerberosPlugin Params: {token.valid=30, delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, token.validity=36000, signer.secret.provider.zookeeper.path=/token, type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper} [junit4] 2> 1242846 INFO (jetty-launcher-1835-thread-1) [n:127.0.0.1:55883_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 1242846 INFO (jetty-launcher-1835-thread-2) [n:127.0.0.1:55888_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 1243075 INFO (jetty-launcher-1835-thread-2-EventThread) [n:127.0.0.1:55888_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED [junit4] 2> 1243076 INFO (jetty-launcher-1835-thread-1-EventThread) [n:127.0.0.1:55883_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED [junit4] 2> 1243398 ERROR (jetty-launcher-1835-thread-2) [n:127.0.0.1:55888_solr ] o.a.h.u.Shell Failed to locate the winutils binary in the hadoop binary path [junit4] 2> java.io.IOException: Could not locate executable null\bin\winutils.exe in the Hadoop binaries. [junit4] 2> at org.apache.hadoop.util.Shell.getQualifiedBinPath(Shell.java:356) [junit4] 2> at org.apache.hadoop.util.Shell.getWinUtilsPath(Shell.java:371) [junit4] 2> at org.apache.hadoop.util.Shell.<clinit>(Shell.java:364) [junit4] 2> at org.apache.hadoop.util.StringUtils.<clinit>(StringUtils.java:80) [junit4] 2> at org.apache.hadoop.conf.Configuration.getBoolean(Configuration.java:1437) [junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenManager.<init>(DelegationTokenManager.java:115) [junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.initTokenManager(DelegationTokenAuthenticationHandler.java:148) [junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.init(DelegationTokenAuthenticationHandler.java:118) [junit4] 2> at org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamDelegationTokenAuthenticationHandler.init(HttpParamDelegationTokenPlugin.java:178) [junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationFilter.initializeAuthHandler(AuthenticationFilter.java:238) [junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationFilter.initializeAuthHandler(DelegationTokenAuthenticationFilter.java:209) [junit4] 2> at org.apache.solr.security.DelegationTokenKerberosFilter.initializeAuthHandler(DelegationTokenKerberosFilter.java:142) [junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationFilter.init(AuthenticationFilter.java:227) [junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationFilter.init(DelegationTokenAuthenticationFilter.java:175) [junit4] 2> at org.apache.solr.security.DelegationTokenKerberosFilter.init(DelegationTokenKerberosFilter.java:71) [junit4] 2> at org.apache.solr.security.HttpParamDelegationTokenPlugin.init(HttpParamDelegationTokenPlugin.java:134) [junit4] 2> at org.apache.solr.core.CoreContainer.initializeAuthenticationPlugin(CoreContainer.java:342) [junit4] 2> at org.apache.solr.core.CoreContainer.reloadSecurityProperties(CoreContainer.java:631) [junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:504) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:235) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:167) [junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:137) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:873) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1565) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1599) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1285) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1130) [junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:448) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:306) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179) [junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367) [junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:376) [junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:239) [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> 1243755 INFO (jetty-launcher-1835-thread-1) [n:127.0.0.1:55883_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A99994290E403D27-001\tempDir-001\node1\. [junit4] 2> 1243755 INFO (jetty-launcher-1835-thread-2) [n:127.0.0.1:55888_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A99994290E403D27-001\tempDir-001\node2\. [junit4] 2> 1244106 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail [junit4] 2> 1246067 WARN (qtp1968489003-11539) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: foo tries to renew a token with renewer bar [junit4] 2> 1246068 WARN (qtp1968489003-11539) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 1246092 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246125 WARN (qtp882287440-11529) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: foo tries to renew a token with renewer bar [junit4] 2> 1246126 WARN (qtp882287440-11529) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 1246127 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246144 WARN (qtp1968489003-11541) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token with renewer renewUser [junit4] 2> 1246145 WARN (qtp1968489003-11541) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 1246145 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246146 WARN (qtp882287440-11529) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token with renewer renewUser [junit4] 2> 1246146 WARN (qtp882287440-11529) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 1246147 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246147 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail [junit4] 2> 1246211 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail [junit4] 2> 1246282 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithDelegationTokens -Dtests.method=testDelegationTokenCancelFail -Dtests.seed=A99994290E403D27 -Dtests.slow=true -Dtests.locale=sr -Dtests.timezone=Pacific/Rarotonga -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 0.14s J0 | TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail <<< [junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<404> [junit4] > at __randomizedtesting.SeedInfo.seed([A99994290E403D27:C126A103DEDA2FCB]:0) [junit4] > at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:140) [junit4] > at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:294) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246318 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient [junit4] 2> 1246342 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246375 INFO (qtp1968489003-11553) [n:127.0.0.1:55888_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE70igFaDGjS9AQDFHmyAnELWaHEMcGuPl_wf2NKl2GZB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246375 INFO (qtp1968489003-11553) [n:127.0.0.1:55888_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE70igFaDGjS9AQDFHmyAnELWaHEMcGuPl_wf2NKl2GZB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246376 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient [junit4] 2> 1246405 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify [junit4] 2> 1246447 INFO (qtp1968489003-11542) [n:127.0.0.1:55888_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE9KigFaDGjTSgUDFNCSCtO2JQDfvRne7b_CEGypvhN3B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246447 INFO (qtp1968489003-11542) [n:127.0.0.1:55888_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE9KigFaDGjTSgUDFNCSCtO2JQDfvRne7b_CEGypvhN3B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246460 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A99994290E403D27]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1246463 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A99994290E403D27]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55879/solr ready [junit4] 2> 1246470 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246472 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e000a, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246480 INFO (qtp882287440-11548) [n:127.0.0.1:55883_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE9KigFaDGjTSgUDFNCSCtO2JQDfvRne7b_CEGypvhN3B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246480 INFO (qtp882287440-11548) [n:127.0.0.1:55883_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE9KigFaDGjTSgUDFNCSCtO2JQDfvRne7b_CEGypvhN3B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246489 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A99994290E403D27]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1246489 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A99994290E403D27]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55879/solr ready [junit4] 2> 1246495 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246496 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e000b, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246497 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify [junit4] 2> 1246524 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew [junit4] 2> 1246557 INFO (qtp1968489003-11553) [n:127.0.0.1:55888_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE_BigFaDGjTwQYDFLFwUm-IKpA8ZAdqAvJA9l2FtgKQB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246557 INFO (qtp1968489003-11553) [n:127.0.0.1:55888_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE_BigFaDGjTwQYDFLFwUm-IKpA8ZAdqAvJA9l2FtgKQB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246562 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1246563 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55879/solr ready [junit4] 2> 1246568 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246570 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e000c, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246579 INFO (qtp882287440-11547) [n:127.0.0.1:55883_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE_BigFaDGjTwQYDFLFwUm-IKpA8ZAdqAvJA9l2FtgKQB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246580 INFO (qtp882287440-11547) [n:127.0.0.1:55883_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXE_BigFaDGjTwQYDFLFwUm-IKpA8ZAdqAvJA9l2FtgKQB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246586 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246615 INFO (qtp1968489003-11543) [n:127.0.0.1:55888_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFAAigFaDGjUAAcDFF6-a7JJcUUk470AyjBhA9aIRDlpB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246615 INFO (qtp1968489003-11543) [n:127.0.0.1:55888_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFAAigFaDGjUAAcDFF6-a7JJcUUk470AyjBhA9aIRDlpB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246626 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1246628 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55879/solr ready [junit4] 2> 1246632 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246633 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e000d, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246640 INFO (qtp882287440-11532) [n:127.0.0.1:55883_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFAAigFaDGjUAAcDFF6-a7JJcUUk470AyjBhA9aIRDlpB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246640 INFO (qtp882287440-11532) [n:127.0.0.1:55883_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFAAigFaDGjUAAcDFF6-a7JJcUUk470AyjBhA9aIRDlpB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246648 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246666 INFO (qtp1968489003-11553) [n:127.0.0.1:55888_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFA9igFaDGjUPQgDFAu2tTpR_1rJaaDw11teuYdgiGptB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246666 INFO (qtp1968489003-11553) [n:127.0.0.1:55888_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFA9igFaDGjUPQgDFAu2tTpR_1rJaaDw11teuYdgiGptB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246674 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1246676 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55879/solr ready [junit4] 2> 1246679 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246681 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e000e, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246688 INFO (qtp882287440-11525) [n:127.0.0.1:55883_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFA9igFaDGjUPQgDFAu2tTpR_1rJaaDw11teuYdgiGptB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246688 INFO (qtp882287440-11525) [n:127.0.0.1:55883_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFA9igFaDGjUPQgDFAu2tTpR_1rJaaDw11teuYdgiGptB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246695 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246716 INFO (qtp1968489003-11550) [n:127.0.0.1:55888_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFBsigFaDGjUbAkDFFt2Jo3mxbxUDk1pSZBGb8fqh-UoB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246716 INFO (qtp1968489003-11550) [n:127.0.0.1:55888_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFBsigFaDGjUbAkDFFt2Jo3mxbxUDk1pSZBGb8fqh-UoB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246722 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1246724 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55879/solr ready [junit4] 2> 1246728 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246729 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e000f, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246737 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1246738 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55879/solr ready [junit4] 2> 1246742 INFO (qtp882287440-11549) [n:127.0.0.1:55883_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFBsigFaDGjUbAkDFFt2Jo3mxbxUDk1pSZBGb8fqh-UoB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246743 INFO (qtp882287440-11549) [n:127.0.0.1:55883_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnoXFBsigFaDGjUbAkDFFt2Jo3mxbxUDk1pSZBGb8fqh-UoB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246744 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e0010, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246755 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246755 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew [junit4] 2> 1246779 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel [junit4] 2> 1246815 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A99994290E403D27]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1246816 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A99994290E403D27]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55879/solr ready [junit4] 2> 1246821 ERROR (qtp882287440-11525) [n:127.0.0.1:55883_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_10] [junit4] 2> 1246856 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e0011, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1246862 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246870 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246896 ERROR (qtp882287440-11547) [n:127.0.0.1:55883_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_11] [junit4] 2> 1246912 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246923 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT [junit4] 2> 1246923 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel [junit4] 2> 1246943 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp [junit4] 2> 1246949 INFO (qtp1968489003-11552) [n:127.0.0.1:55888_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params op=someSolrOperation&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1246950 INFO (qtp1968489003-11552) [n:127.0.0.1:55888_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={op=someSolrOperation&action=LIST&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1246953 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[A99994290E403D27]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bar&p=bar&t=test&e=1485698081385&s=S3o/DzMplfR+maS3bGznRqHmrRE="; Path=/; Domain=127.0.0.1; Expires= , 29- -2017 13:54:41 GMT; Secure; HttpOnly". Invalid 'expires' attribute: , 29- -2017 13:54:41 GMT [junit4] 2> 1246954 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp [junit4] 2> 1247010 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths [junit4] 2> 1247089 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x159e85c3d4e0012, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1247089 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[A99994290E403D27]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths [junit4] 2> 1247091 INFO (jetty-launcher-1835-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@12541795{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 1247094 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=99791879 [junit4] 2> 1247096 INFO (jetty-launcher-1835-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@84a8521{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 1247096 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=864049100 [junit4] 2> 1247105 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.c.Overseer Overseer (id=97364349828333573-127.0.0.1:55883_solr-n_0000000000) closing [junit4] 2> 1247105 INFO (OverseerStateUpdate-97364349828333573-127.0.0.1:55883_solr-n_0000000000) [n:127.0.0.1:55883_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:55883_solr [junit4] 2> 1247107 WARN (zkCallback-1845-thread-2-processing-n:127.0.0.1:55883_solr) [n:127.0.0.1:55883_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1247108 INFO (jetty-launcher-1835-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1247108 ERROR (Thread[Thread-3298,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:55883_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted [junit4] 2> 1247114 WARN (zkCallback-1846-thread-2-processing-n:127.0.0.1:55888_solr) [n:127.0.0.1:55888_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1247117 INFO (jetty-launcher-1835-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1247117 ERROR (Thread[Thread-3300,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:55888_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted [junit4] 2> 1247152 INFO (jetty-launcher-1835-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@32617d6{/solr,null,UNAVAILABLE} [junit4] 2> 1247152 INFO (jetty-launcher-1835-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4d166e96{/solr,null,UNAVAILABLE} [junit4] 2> 1247157 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A99994290E403D27]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55879 55879 [junit4] 2> 1247158 INFO (Thread-3275) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55879 55879 [junit4] 2> 1247160 WARN (Thread-3275) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 10 /solr/aliases.json [junit4] 2> 2 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 10 /solr/clusterprops.json [junit4] 2> 10 /solr/clusterstate.json [junit4] 2> 4 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_7 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_6 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_9 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_8 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_1 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_3 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_3 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_2 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_5 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_12 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_11 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 10 /solr/live_nodes [junit4] 2> 10 /solr/collections [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/queue [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot [junit4] 2> 2 /solr/overseer/queue-work [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot [junit4] 2> [junit4] 2> 1247160 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A99994290E403D27]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A99994290E403D27-001 [junit4] 2> Jan 29, 2017 3:54:41 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 6 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1466, maxMBSortInHeap=5.7965385863472125, sim=RandomSimilarity(queryNorm=true): {}, locale=sr, timezone=Pacific/Rarotonga [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=3,threads=1,free=31533808,total=347414528 [junit4] 2> NOTE: All tests run in this JVM: [SuggesterFSTTest, BJQParserTest, TestFieldCollectionResource, CursorMarkTest, HdfsBasicDistributedZkTest, TestCoreContainer, UnloadDistributedZkTest, TestReplicationHandler, TestSolrXml, RecoveryZkTest, DistributedQueryComponentCustomSortTest, DistributedQueueTest, TestRandomFlRTGCloud, RankQueryTest, TermsComponentTest, HttpSolrCallGetCoreTest, ClassificationUpdateProcessorFactoryTest, TestSha256AuthenticationProvider, XmlUpdateRequestHandlerTest, TestRecoveryHdfs, TestMaxScoreQueryParser, TestMiniSolrCloudCluster, TestManagedSchemaThreadSafety, TestMiniSolrCloudClusterSSL, TestExpandComponent, StandardRequestHandlerTest, BigEndianAscendingWordSerializerTest, TestStressVersions, TestNamedUpdateProcessors, PeerSyncReplicationTest, TestSolrCoreSnapshots, ZkControllerTest, SimplePostToolTest, TestSchemaSimilarityResource, DirectSolrConnectionTest, PKIAuthenticationIntegrationTest, CurrencyFieldXmlFileTest, CloudMLTQParserTest, TestConfigSets, PluginInfoTest, TestClassicSimilarityFactory, LeaderElectionTest, HdfsNNFailoverTest, PolyFieldTest, TimeZoneUtilsTest, TestInPlaceUpdatesDistrib, TestClusterProperties, TestClusterStateMutator, HdfsChaosMonkeySafeLeaderTest, PathHierarchyTokenizerFactoryTest, CdcrRequestHandlerTest, CdcrReplicationDistributedZkTest, TestLocalFSCloudBackupRestore, SparseHLLTest, TestStressReorder, TestSchemaNameResource, ConjunctionSolrSpellCheckerTest, TestUninvertingReader, TestSchemaManager, TestSurroundQueryParser, TestObjectReleaseTracker, DocExpirationUpdateProcessorFactoryTest, ZkNodePropsTest, ActionThrottleTest, BasicAuthIntegrationTest, DocValuesTest, SchemaVersionSpecificBehaviorTest, StatelessScriptUpdateProcessorFactoryTest, AssignTest, TestFastLRUCache, TestSolrJ, TestFaceting, TestReqParamsAPI, TestFreeTextSuggestions, UUIDUpdateProcessorFallbackTest, TestFieldCacheWithThreads, DocumentBuilderTest, TestDistributedSearch, TestJmxIntegration, TestTrackingShardHandlerFactory, TestCollapseQParserPlugin, TestBackupRepositoryFactory, TestSolrCloudWithSecureImpersonation, HardAutoCommitTest, TestCloudInspectUtil, SolrTestCaseJ4Test, TestLeaderElectionZkExpiry, TestLazyCores, DirectSolrSpellCheckerTest, TestIBSimilarityFactory, DistributedSuggestComponentTest, RuleEngineTest, BadCopyFieldTest, TestSolrConfigHandler, TestDeleteCollectionOnDownNodes, SyncSliceTest, SolrSlf4jReporterTest, TestBM25SimilarityFactory, SearchHandlerTest, BinaryUpdateRequestHandlerTest, TestHighlightDedupGrouping, AlternateDirectoryTest, TestCharFilters, SmileWriterTest, FastVectorHighlighterTest, TestDocumentBuilder, TestSolrCoreParser, TestExactStatsCache, DisMaxRequestHandlerTest, TestCustomStream, TestWordDelimiterFilterFactory, TestPartialUpdateDeduplication, HdfsDirectoryFactoryTest, OutputWriterTest, TestSubQueryTransformerDistrib, DistributedFacetPivotSmallTest, OverseerStatusTest, TestCloudPseudoReturnFields, IndexSchemaRuntimeFieldTest, HighlighterMaxOffsetTest, RemoteQueryErrorTest, DistributedSpellCheckComponentTest, TestInitParams, TestPhraseSuggestions, HdfsLockFactoryTest, XsltUpdateRequestHandlerTest, HdfsRecoveryZkTest, TestFieldCache, TestSQLHandler, TestCloudSchemaless, SolrMetricManagerTest, SolrCoreTest, MBeansHandlerTest, TestCSVLoader, DistributedFacetExistsSmallTest, TestShortCircuitedRequests, CheckHdfsIndexTest, TestCollationField, MoreLikeThisHandlerTest, SolrIndexMetricsTest, TestComplexPhraseQParserPlugin, TestManagedStopFilterFactory, OverseerModifyCollectionTest, ConcurrentDeleteAndCreateCollectionTest, SolrIndexConfigTest, TestMacros, TestSSLRandomization, TestGeoJSONResponseWriter, SystemInfoHandlerTest, TestSweetSpotSimilarityFactory, TestSlowCompositeReaderWrapper, TestUseDocValuesAsStored, CachingDirectoryFactoryTest, DataDrivenBlockJoinTest, VMParamsZkACLAndCredentialsProvidersTest, TestSmileRequest, TestInitQParser, SOLR749Test, TestJettySolrRunner, TestFastOutputStream, ExplicitHLLTest, CopyFieldTest, BlockCacheTest, SolrMetricsIntegrationTest, TestSolrCloudWithDelegationTokens] [junit4] Completed [315/684 (1!)] on J0 in 5.93s, 8 tests, 1 failure <<< FAILURES! [...truncated 63616 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org