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/17 16:58:14 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_102) - Build #
6187 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6187/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseG1GC
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([2E82153B065AB7B4:463D2011D6C0A558]: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: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 10884 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_2E82153B065AB7B4-001\init-core-data-001
[junit4] 2> 375668 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[2E82153B065AB7B4]-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> 375676 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[2E82153B065AB7B4]-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_2E82153B065AB7B4-001\tempDir-001
[junit4] 2> 375677 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[2E82153B065AB7B4]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 375680 INFO (Thread-458) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 375680 INFO (Thread-458) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 375779 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[2E82153B065AB7B4]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:65347
[junit4] 2> 375956 INFO (jetty-launcher-444-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 375956 INFO (jetty-launcher-444-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 375956 INFO (jetty-launcher-444-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5423012{/solr,null,AVAILABLE}
[junit4] 2> 375957 INFO (jetty-launcher-444-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1544cd8{/solr,null,AVAILABLE}
[junit4] 2> 376161 INFO (jetty-launcher-444-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@e8b4590{SSL,[ssl, http/1.1]}{127.0.0.1:65351}
[junit4] 2> 376161 INFO (jetty-launcher-444-thread-2) [ ] o.e.j.s.Server Started @386258ms
[junit4] 2> 376161 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65351}
[junit4] 2> 376162 ERROR (jetty-launcher-444-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 376163 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 376163 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 376163 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 376163 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-17T15:41:19.209Z
[junit4] 2> 376189 INFO (jetty-launcher-444-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@7cc33d04{SSL,[ssl, http/1.1]}{127.0.0.1:65352}
[junit4] 2> 376189 INFO (jetty-launcher-444-thread-1) [ ] o.e.j.s.Server Started @386286ms
[junit4] 2> 376189 INFO (jetty-launcher-444-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65352}
[junit4] 2> 376190 ERROR (jetty-launcher-444-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 376190 INFO (jetty-launcher-444-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 376190 INFO (jetty-launcher-444-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 376190 INFO (jetty-launcher-444-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 376190 INFO (jetty-launcher-444-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-17T15:41:19.236Z
[junit4] 2> 376201 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 376204 INFO (jetty-launcher-444-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 376210 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 0x157d34de00c0001, 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> 376218 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65347/solr
[junit4] 2> 376218 INFO (jetty-launcher-444-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65347/solr
[junit4] 2> 376266 INFO (jetty-launcher-444-thread-1) [n:127.0.0.1:65352_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:65352_solr
[junit4] 2> 376267 INFO (jetty-launcher-444-thread-2) [n:127.0.0.1:65351_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65351_solr
[junit4] 2> 376268 INFO (jetty-launcher-444-thread-1) [n:127.0.0.1:65352_solr ] o.a.s.c.Overseer Overseer (id=96778248436580358-127.0.0.1:65352_solr-n_0000000000) starting
[junit4] 2> 376270 INFO (zkCallback-454-thread-1-processing-n:127.0.0.1:65351_solr) [n:127.0.0.1:65351_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 376274 INFO (zkCallback-455-thread-1-processing-n:127.0.0.1:65352_solr) [n:127.0.0.1:65352_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 376283 INFO (jetty-launcher-444-thread-2) [n:127.0.0.1:65351_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 376304 INFO (jetty-launcher-444-thread-1) [n:127.0.0.1:65352_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65352_solr
[junit4] 2> 376340 INFO (jetty-launcher-444-thread-1) [n:127.0.0.1:65352_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 376349 INFO (jetty-launcher-444-thread-2) [n:127.0.0.1:65351_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> 376452 INFO (zkCallback-454-thread-1-processing-n:127.0.0.1:65351_solr) [n:127.0.0.1:65351_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 376452 INFO (zkCallback-455-thread-1-processing-n:127.0.0.1:65352_solr) [n:127.0.0.1:65352_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 376453 INFO (jetty-launcher-444-thread-1) [n:127.0.0.1:65352_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> 376765 INFO (jetty-launcher-444-thread-2) [n:127.0.0.1:65351_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 376765 INFO (jetty-launcher-444-thread-1) [n:127.0.0.1:65352_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 376880 INFO (jetty-launcher-444-thread-2-EventThread) [n:127.0.0.1:65351_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 376880 INFO (jetty-launcher-444-thread-1-EventThread) [n:127.0.0.1:65352_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 377102 ERROR (jetty-launcher-444-thread-2) [n:127.0.0.1:65351_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:137)
[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:66)
[junit4] 2> at org.apache.solr.security.HttpParamDelegationTokenPlugin.init(HttpParamDelegationTokenPlugin.java:134)
[junit4] 2> at org.apache.solr.core.CoreContainer.initializeAuthenticationPlugin(CoreContainer.java:320)
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:468)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:204)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:153)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:137)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:856)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1542)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1576)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1268)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1113)
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:447)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:258)
[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:342)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:315)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:353)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:220)
[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> 377442 INFO (jetty-launcher-444-thread-2) [n:127.0.0.1:65351_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_2E82153B065AB7B4-001\tempDir-001\node2\.
[junit4] 2> 377443 INFO (jetty-launcher-444-thread-1) [n:127.0.0.1:65352_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_2E82153B065AB7B4-001\tempDir-001\node1\.
[junit4] 2> 377480 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
[junit4] 2> 379200 WARN (qtp414399918-2118) [ ] 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> 379214 WARN (qtp414399918-2118) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 379236 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379268 WARN (qtp950076054-2124) [ ] 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> 379270 WARN (qtp950076054-2124) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 379272 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379288 WARN (qtp414399918-2113) [ ] 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> 379289 WARN (qtp414399918-2113) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 379289 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379291 WARN (qtp950076054-2125) [ ] 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> 379292 WARN (qtp950076054-2125) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 379293 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379293 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
[junit4] 2> 379324 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
[junit4] 2> 379392 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379415 INFO (qtp950076054-2126) [n:127.0.0.1:65352_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe5EigFX91pyRAMEFEslOkdlxiXTHmvdKXyRmG9r64HeB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379415 INFO (qtp950076054-2126) [n:127.0.0.1:65352_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe5EigFX91pyRAMEFEslOkdlxiXTHmvdKXyRmG9r64HeB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379417 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 0x157d34de00c000a, 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> 379435 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379444 INFO (qtp950076054-2124) [n:127.0.0.1:65352_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe5EigFX91pyRAMEFEslOkdlxiXTHmvdKXyRmG9r64HeB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379445 INFO (qtp950076054-2124) [n:127.0.0.1:65352_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe5EigFX91pyRAMEFEslOkdlxiXTHmvdKXyRmG9r64HeB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379452 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379463 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379465 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 0x157d34de00c000b, 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> 379500 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379508 INFO (qtp950076054-2127) [n:127.0.0.1:65352_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe7QigFX91py0AQEFNuZkgPzIWhoy7yboqGh_Fqxq4hDB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379508 INFO (qtp950076054-2127) [n:127.0.0.1:65352_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe7QigFX91py0AQEFNuZkgPzIWhoy7yboqGh_Fqxq4hDB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379509 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 0x157d34de00c000c, 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> 379520 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379527 INFO (qtp950076054-2125) [n:127.0.0.1:65352_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe7QigFX91py0AQEFNuZkgPzIWhoy7yboqGh_Fqxq4hDB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379527 INFO (qtp950076054-2125) [n:127.0.0.1:65352_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe7QigFX91py0AQEFNuZkgPzIWhoy7yboqGh_Fqxq4hDB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379535 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379543 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379544 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 0x157d34de00c000d, 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> 379564 INFO (qtp414399918-2115) [n:127.0.0.1:65351_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe8figFX91pzHwUEFOAeIe1ZHamxRk7RFlRFyuvcMqroB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379564 INFO (qtp414399918-2115) [n:127.0.0.1:65351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe8figFX91pzHwUEFOAeIe1ZHamxRk7RFlRFyuvcMqroB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379571 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379581 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379581 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 0x157d34de00c000e, 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> 379588 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379596 INFO (qtp950076054-2120) [n:127.0.0.1:65352_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe8figFX91pzHwUEFOAeIe1ZHamxRk7RFlRFyuvcMqroB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379596 INFO (qtp950076054-2120) [n:127.0.0.1:65352_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe8figFX91pzHwUEFOAeIe1ZHamxRk7RFlRFyuvcMqroB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379597 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 0x157d34de00c000f, 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> 379606 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379615 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379616 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 0x157d34de00c0010, 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> 379641 INFO (qtp414399918-2114) [n:127.0.0.1:65351_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe9oigFX91pzaAYEFMryBFtT7YqlXSFM87SalQN7H80uB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379641 INFO (qtp414399918-2114) [n:127.0.0.1:65351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe9oigFX91pzaAYEFMryBFtT7YqlXSFM87SalQN7H80uB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379648 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379657 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379658 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 0x157d34de00c0011, 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> 379665 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379674 INFO (qtp950076054-2123) [n:127.0.0.1:65352_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe9oigFX91pzaAYEFMryBFtT7YqlXSFM87SalQN7H80uB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379674 INFO (qtp950076054-2123) [n:127.0.0.1:65352_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTe9oigFX91pzaAYEFMryBFtT7YqlXSFM87SalQN7H80uB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379675 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 0x157d34de00c0012, 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> 379686 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379686 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
[junit4] 2> 379714 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
[junit4] 2> 379752 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379760 ERROR (qtp950076054-2125) [n:127.0.0.1:65352_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_7]
[junit4] 2> 379790 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 0x157d34de00c0013, 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> 379797 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379807 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379808 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 0x157d34de00c0014, 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> 379819 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379840 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379849 ERROR (qtp950076054-2125) [n:127.0.0.1:65352_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_8]
[junit4] 2> 379851 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 0x157d34de00c0015, 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> 379861 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379873 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379887 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379889 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 0x157d34de00c0016, 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> 379890 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
[junit4] 2> 379916 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
[junit4] 2> 379934 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379948 INFO (qtp950076054-2120) [n:127.0.0.1:65352_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTfCTigFX91p0kwkEFPwkmn5khiauq9IVTU2mla9i-LJPB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 379948 INFO (qtp950076054-2120) [n:127.0.0.1:65352_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTfCTigFX91p0kwkEFPwkmn5khiauq9IVTU2mla9i-LJPB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 379950 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 0x157d34de00c0017, 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> 379976 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 379983 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 379984 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 0x157d34de00c0018, 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> 379993 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[2E82153B065AB7B4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 380002 INFO (qtp950076054-2120) [n:127.0.0.1:65352_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTfCTigFX91p0kwkEFPwkmn5khiauq9IVTU2mla9i-LJPB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 380002 INFO (qtp950076054-2120) [n:127.0.0.1:65352_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTfCTigFX91p0kwkEFPwkmn5khiauq9IVTU2mla9i-LJPB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 380003 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 0x157d34de00c0019, 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> 380012 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 380012 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
[junit4] 2> 380040 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
[junit4] 2> 380063 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithDelegationTokens -Dtests.method=testDelegationTokenCancelFail -Dtests.seed=2E82153B065AB7B4 -Dtests.slow=true -Dtests.locale=de-CH -Dtests.timezone=America/Atka -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.05s J0 | TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<404>
[junit4] > at __randomizedtesting.SeedInfo.seed([2E82153B065AB7B4:463D2011D6C0A558]: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> 380094 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
[junit4] 2> 380119 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=Do, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Do, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 380132 INFO (qtp414399918-2118) [n:127.0.0.1:65351_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTfFFigFX91p1RQsEFH2ym15zdTb0NssT_uj006_Yke7pB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 380132 INFO (qtp414399918-2118) [n:127.0.0.1:65351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfTTfFFigFX91p1RQsEFH2ym15zdTb0NssT_uj006_Yke7pB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 380134 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
[junit4] 2> 380165 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
[junit4] 2> 380175 INFO (qtp414399918-2115) [n:127.0.0.1:65351_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> 380175 INFO (qtp414399918-2115) [n:127.0.0.1:65351_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> 380176 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[2E82153B065AB7B4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bar&p=bar&t=test&e=1476754883221&s=A8qjr5qNa+6ncT2HVEXApzfJ1QM="; Path=/; Domain=127.0.0.1; Expires=Di, 18-Okt-2016 01:41:23 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Di, 18-Okt-2016 01:41:23 GMT
[junit4] 2> 380176 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
[junit4] 2> 380200 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
[junit4] 2> 380212 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 0x157d34de00c001a, 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> 380213 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[2E82153B065AB7B4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
[junit4] 2> 380216 INFO (jetty-launcher-444-thread-1) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@e8b4590{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 380217 INFO (jetty-launcher-444-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=895993916
[junit4] 2> 380218 INFO (jetty-launcher-444-thread-2) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@7cc33d04{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 380218 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1748919857
[junit4] 2> 380224 INFO (jetty-launcher-444-thread-2) [ ] o.a.s.c.Overseer Overseer (id=96778248436580358-127.0.0.1:65352_solr-n_0000000000) closing
[junit4] 2> 380224 INFO (OverseerStateUpdate-96778248436580358-127.0.0.1:65352_solr-n_0000000000) [n:127.0.0.1:65352_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:65352_solr
[junit4] 2> 380226 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 0x157d34de00c0005, 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> 381725 WARN (zkCallback-454-thread-1-processing-n:127.0.0.1:65351_solr) [n:127.0.0.1:65351_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 381725 ERROR (Thread[Thread-475,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:65351_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 381726 WARN (zkCallback-455-thread-2-processing-n:127.0.0.1:65352_solr) [n:127.0.0.1:65352_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 381726 ERROR (Thread[Thread-476,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:65352_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 381754 INFO (jetty-launcher-444-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1544cd8{/solr,null,UNAVAILABLE}
[junit4] 2> 381756 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 0x157d34de00c0007, 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> 381756 INFO (jetty-launcher-444-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5423012{/solr,null,UNAVAILABLE}
[junit4] 2> 381759 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[2E82153B065AB7B4]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65347 65347
[junit4] 2> 381761 INFO (Thread-458) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65347 65347
[junit4] 2> 381763 WARN (Thread-458) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/clusterprops.json
[junit4] 2> 18 /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> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/live_nodes
[junit4] 2> 18 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/overseer/collection-queue-work
[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> 381763 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[2E82153B065AB7B4]-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_2E82153B065AB7B4-001
[junit4] 2> Oct 17, 2016 3:41:24 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=395, maxMBSortInHeap=6.54113817125637, sim=ClassicSimilarity, locale=de-CH, timezone=America/Atka
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=157121944,total=279969792
[junit4] 2> NOTE: All tests run in this JVM: [TestClassicSimilarityFactory, DistributedSpellCheckComponentTest, TestBM25SimilarityFactory, AssignTest, RequestLoggingTest, DistribDocExpirationUpdateProcessorTest, TestBinaryField, AddSchemaFieldsUpdateProcessorFactoryTest, DeleteReplicaTest, RequiredFieldsTest, ResponseHeaderTest, TestOrdValues, ZkControllerTest, CoreAdminRequestStatusTest, RankQueryTest, MoreLikeThisHandlerTest, HdfsBasicDistributedZk2Test, ReplaceNodeTest, MigrateRouteKeyTest, TestDocumentBuilder, TestDistributedSearch, TestCustomDocTransformer, OverseerTest, SuggestComponentTest, QueryElevationComponentTest, TestSolrCloudWithDelegationTokens]
[junit4] Completed [87/638 (1!)] on J0 in 6.38s, 8 tests, 1 failure <<< FAILURES!
[...truncated 62148 lines...]
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_102) - Build #
6188 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6188/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.lucene.store.TestSimpleFSDirectory
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001\tempDir-005: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001\tempDir-005 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001\tempDir-005: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001\tempDir-005
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001
at __randomizedtesting.SeedInfo.seed([4D86F1E019BCDC1A]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail
Error Message:
expected:<200> but was:<404>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<404>
at __randomizedtesting.SeedInfo.seed([EF326B30FE0BA291:878D5E1A2E91B07D]: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: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 497 lines...]
[junit4] Suite: org.apache.lucene.store.TestSimpleFSDirectory
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=621, maxMBSortInHeap=6.476689802604241, sim=ClassicSimilarity, locale=zh-TW, timezone=Africa/Addis_Ababa
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=316230224,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestMergeRateLimiter, TestArrayUtil, TestDocumentWriter, TestUsageTrackingFilterCachingPolicy, TestExternalCodecs, TestBagOfPostings, TestPerSegmentDeletes, TestDirectoryReaderReopen, Test2BSortedDocValuesFixedSorted, TestCustomNorms, TestBytesRef, TestSPIClassIterator, TestPayloads, TestFilterDirectoryReader, TestElevationComparator, TestBytesRefHash, TestPrefixQuery, TestStringHelper, TestCodecHoldsOpenFiles, TestIndexableField, TestSortedSetDocValues, TestNativeFSLockFactory, TestTryDelete, TestIndexWriterMaxDocs, TestBlockPostingsFormat, TestIndexWriterNRTIsCurrent, FiniteStringsIteratorTest, TestTermsEnum, TestHugeRamFile, TestPackedTokenAttributeImpl, TestUnicodeUtil, TestPerFieldDocValuesFormat, TestAddIndexes, TestFlex, TestSimpleFSDirectory]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSimpleFSDirectory -Dtests.seed=4D86F1E019BCDC1A -Dtests.slow=true -Dtests.locale=zh-TW -Dtests.timezone=Africa/Addis_Ababa -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestSimpleFSDirectory (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001\tempDir-005: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001\tempDir-005
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSDirectory_4D86F1E019BCDC1A-001
[junit4] > at __randomizedtesting.SeedInfo.seed([4D86F1E019BCDC1A]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [94/434 (1!)] on J1 in 2.89s, 42 tests, 1 error <<< FAILURES!
[...truncated 10307 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_EF326B30FE0BA291-001\init-core-data-001
[junit4] 2> 313675 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[EF326B30FE0BA291]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 313684 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[EF326B30FE0BA291]-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_EF326B30FE0BA291-001\tempDir-001
[junit4] 2> 313684 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[EF326B30FE0BA291]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 313684 INFO (Thread-441) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 313684 INFO (Thread-441) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 313785 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[EF326B30FE0BA291]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:50698
[junit4] 2> 313827 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 313827 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 313828 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c03c25b{/solr,null,AVAILABLE}
[junit4] 2> 313828 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@125e9408{/solr,null,AVAILABLE}
[junit4] 2> 314010 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@492b0abf{SSL,[ssl, http/1.1]}{127.0.0.1:50705}
[junit4] 2> 314010 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.Server Started @319195ms
[junit4] 2> 314012 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50705}
[junit4] 2> 314012 ERROR (jetty-launcher-333-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 314012 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 314012 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 314012 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 314012 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-18T01:36:27.827Z
[junit4] 2> 314018 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@1b1bfa0e{SSL,[ssl, http/1.1]}{127.0.0.1:50706}
[junit4] 2> 314018 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.Server Started @319202ms
[junit4] 2> 314018 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50706}
[junit4] 2> 314018 ERROR (jetty-launcher-333-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 314018 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 314018 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 314018 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 314018 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-18T01:36:27.833Z
[junit4] 2> 314030 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 314030 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 314045 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 0x157d56ebf6f0002, 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> 314047 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 0x157d56ebf6f0001, 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> 314051 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50698/solr
[junit4] 2> 314051 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50698/solr
[junit4] 2> 314059 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 0x157d56ebf6f0003, 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> 314062 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 0x157d56ebf6f0004, 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> 314097 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:50706_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50706_solr
[junit4] 2> 314097 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:50705_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50705_solr
[junit4] 2> 314098 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:50706_solr ] o.a.s.c.Overseer Overseer (id=96780588646596614-127.0.0.1:50706_solr-n_0000000000) starting
[junit4] 2> 314100 INFO (zkCallback-344-thread-1-processing-n:127.0.0.1:50706_solr) [n:127.0.0.1:50706_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 314100 INFO (zkCallback-343-thread-1-processing-n:127.0.0.1:50705_solr) [n:127.0.0.1:50705_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 314110 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:50706_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50706_solr
[junit4] 2> 314111 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:50705_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 314113 INFO (zkCallback-343-thread-1-processing-n:127.0.0.1:50705_solr) [n:127.0.0.1:50705_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 314113 INFO (OverseerStateUpdate-96780588646596614-127.0.0.1:50706_solr-n_0000000000) [n:127.0.0.1:50706_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 314119 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:50706_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 314162 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:50705_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> 314162 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:50706_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> 314484 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:50706_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 314484 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:50705_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 314543 INFO (jetty-launcher-333-thread-1-EventThread) [n:127.0.0.1:50706_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 314543 INFO (jetty-launcher-333-thread-2-EventThread) [n:127.0.0.1:50705_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 314794 ERROR (jetty-launcher-333-thread-2) [n:127.0.0.1:50705_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:137)
[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:66)
[junit4] 2> at org.apache.solr.security.HttpParamDelegationTokenPlugin.init(HttpParamDelegationTokenPlugin.java:134)
[junit4] 2> at org.apache.solr.core.CoreContainer.initializeAuthenticationPlugin(CoreContainer.java:320)
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:468)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:204)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:153)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:137)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:856)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1542)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1576)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1268)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1113)
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:447)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:258)
[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:342)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:315)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:353)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:220)
[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> 315030 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:50706_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_EF326B30FE0BA291-001\tempDir-001\node1\.
[junit4] 2> 315032 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:50705_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_EF326B30FE0BA291-001\tempDir-001\node2\.
[junit4] 2> 315051 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 0x157d56ebf6f0009, 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> 315083 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
[junit4] 2> 316577 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 316597 ERROR (qtp821610038-2280) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_1]
[junit4] 2> 316628 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 0x157d56ebf6f000a, 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> 316691 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 316699 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 316712 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 316712 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 0x157d56ebf6f000b, 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> 316775 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 316785 ERROR (qtp821610038-2276) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 316788 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 0x157d56ebf6f000c, 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> 316795 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 316807 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 316807 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
[junit4] 2> 316829 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
[junit4] 2> 316841 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 316865 INFO (qtp821610038-2279) [n:127.0.0.1:50705_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbsu1igFX-XtPtQMDFCFxl66D6dUt3wwGJ5uWMIrTzhWpB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 316865 INFO (qtp821610038-2279) [n:127.0.0.1:50705_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbsu1igFX-XtPtQMDFCFxl66D6dUt3wwGJ5uWMIrTzhWpB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 316866 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
[junit4] 2> 316886 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
[junit4] 2> 316891 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 316898 INFO (qtp821610038-2280) [n:127.0.0.1:50705_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> 316898 INFO (qtp821610038-2280) [n:127.0.0.1:50705_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> 316899 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bar&p=bar&t=test&e=1476790590713&s=ZyU+k9OZG2aLu+NeU3CZlU4lsYA="; Path=/; Domain=127.0.0.1; Expires=Ma, 18-Oct-2016 11:36:30 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Ma, 18-Oct-2016 11:36:30 GMT
[junit4] 2> 316902 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 0x157d56ebf6f000d, 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> 316904 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
[junit4] 2> 316927 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
[junit4] 2> 317003 INFO (qtp821610038-2278) [n:127.0.0.1:50705_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbswXigFX-XtQFwQDFK9yC6ZrRrFDtVf5HsYYf1aLDsCoB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317003 INFO (qtp821610038-2278) [n:127.0.0.1:50705_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbswXigFX-XtQFwQDFK9yC6ZrRrFDtVf5HsYYf1aLDsCoB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317023 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317034 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317036 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 0x157d56ebf6f000e, 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> 317046 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317054 INFO (qtp821610038-2279) [n:127.0.0.1:50705_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbswXigFX-XtQFwQDFK9yC6ZrRrFDtVf5HsYYf1aLDsCoB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317054 INFO (qtp821610038-2279) [n:127.0.0.1:50705_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbswXigFX-XtQFwQDFK9yC6ZrRrFDtVf5HsYYf1aLDsCoB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317057 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 0x157d56ebf6f000f, 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> 317101 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317112 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317116 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 0x157d56ebf6f0010, 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> 317117 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
[junit4] 2> 317141 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
[junit4] 2> 317173 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithDelegationTokens -Dtests.method=testDelegationTokenCancelFail -Dtests.seed=EF326B30FE0BA291 -Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=America/Nome -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.06s J0 | TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<404>
[junit4] > at __randomizedtesting.SeedInfo.seed([EF326B30FE0BA291:878D5E1A2E91B07D]: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> 317198 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
[junit4] 2> 317287 INFO (qtp821610038-2275) [n:127.0.0.1:50705_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs0nigFX-XtRJwYDFMfvpuyKjBZ_mksSfZVYB4kN6DYXB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317287 INFO (qtp821610038-2275) [n:127.0.0.1:50705_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs0nigFX-XtRJwYDFMfvpuyKjBZ_mksSfZVYB4kN6DYXB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317375 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317383 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317384 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 0x157d56ebf6f0011, 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> 317397 INFO (qtp1983832863-2282) [n:127.0.0.1:50706_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs0nigFX-XtRJwYDFMfvpuyKjBZ_mksSfZVYB4kN6DYXB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317397 INFO (qtp1983832863-2282) [n:127.0.0.1:50706_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs0nigFX-XtRJwYDFMfvpuyKjBZ_mksSfZVYB4kN6DYXB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317406 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317414 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317419 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 0x157d56ebf6f0012, 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> 317464 INFO (qtp821610038-2270) [n:127.0.0.1:50705_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs4FigFX-XtSBQcDFDOgXTIle3DuCiMtNgtVFw3DIeKsB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317464 INFO (qtp821610038-2270) [n:127.0.0.1:50705_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs4FigFX-XtSBQcDFDOgXTIle3DuCiMtNgtVFw3DIeKsB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317478 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317489 INFO (qtp1983832863-2269) [n:127.0.0.1:50706_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs4FigFX-XtSBQcDFDOgXTIle3DuCiMtNgtVFw3DIeKsB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317490 INFO (qtp1983832863-2269) [n:127.0.0.1:50706_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs4FigFX-XtSBQcDFDOgXTIle3DuCiMtNgtVFw3DIeKsB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317504 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317522 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317529 INFO (qtp821610038-2270) [n:127.0.0.1:50705_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs5YigFX-XtSWAgDFI16AwUhcM-Zy29E7DoZ-vOdPag2B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317529 INFO (qtp821610038-2270) [n:127.0.0.1:50705_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs5YigFX-XtSWAgDFI16AwUhcM-Zy29E7DoZ-vOdPag2B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317530 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 0x157d56ebf6f0013, 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> 317546 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317553 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317564 INFO (qtp821610038-2276) [n:127.0.0.1:50705_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs5YigFX-XtSWAgDFI16AwUhcM-Zy29E7DoZ-vOdPag2B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317565 INFO (qtp821610038-2276) [n:127.0.0.1:50705_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs5YigFX-XtSWAgDFI16AwUhcM-Zy29E7DoZ-vOdPag2B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317567 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 0x157d56ebf6f0014, 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> 317571 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317578 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317580 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 0x157d56ebf6f0015, 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> 317603 INFO (qtp821610038-2274) [n:127.0.0.1:50705_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs6ligFX-XtSpQkDFCyTfyvQEkbZRSrTgprH--TrLv15B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317603 INFO (qtp821610038-2274) [n:127.0.0.1:50705_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs6ligFX-XtSpQkDFCyTfyvQEkbZRSrTgprH--TrLv15B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317616 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 317627 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317628 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 0x157d56ebf6f0016, 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> 317645 INFO (qtp1983832863-2269) [n:127.0.0.1:50706_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs6ligFX-XtSpQkDFCyTfyvQEkbZRSrTgprH--TrLv15B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 317645 INFO (qtp1983832863-2269) [n:127.0.0.1:50706_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVfVbs6ligFX-XtSpQkDFCyTfyvQEkbZRSrTgprH--TrLv15B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 317659 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317659 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
[junit4] 2> 317686 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
[junit4] 2> 317725 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 0x157d56ebf6f0017, 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> 317725 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
[junit4] 2> 317750 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
[junit4] 2> 317765 WARN (qtp821610038-2279) [n:127.0.0.1:50705_solr ] 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> 317765 WARN (qtp821610038-2279) [n:127.0.0.1:50705_solr ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 317766 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317770 WARN (qtp1983832863-2277) [ ] 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> 317770 WARN (qtp1983832863-2277) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 317770 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317789 WARN (qtp821610038-2276) [n:127.0.0.1:50705_solr ] 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> 317790 WARN (qtp821610038-2276) [n:127.0.0.1:50705_solr ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 317790 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317792 WARN (qtp1983832863-2277) [ ] 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> 317793 WARN (qtp1983832863-2277) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 317793 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[EF326B30FE0BA291]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=J, 01-Ian-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: J, 01-Ian-1970 00:00:00 GMT
[junit4] 2> 317793 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[EF326B30FE0BA291]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
[junit4] 2> 317796 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1b1bfa0e{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 317796 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1591541449
[junit4] 2> 317796 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@492b0abf{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 317797 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1227129648
[junit4] 2> 317807 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 0x157d56ebf6f0005, 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> 317808 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.Overseer Overseer (id=96780588646596614-127.0.0.1:50706_solr-n_0000000000) closing
[junit4] 2> 317808 INFO (OverseerStateUpdate-96780588646596614-127.0.0.1:50706_solr-n_0000000000) [n:127.0.0.1:50706_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50706_solr
[junit4] 2> 317809 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 0x157d56ebf6f0006, 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> 317810 ERROR (Thread[Thread-460,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:50705_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 317824 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 0x157d56ebf6f0008, 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> 317826 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7c03c25b{/solr,null,UNAVAILABLE}
[junit4] 2> 319308 WARN (zkCallback-344-thread-3-processing-n:127.0.0.1:50706_solr) [n:127.0.0.1:50706_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 319308 ERROR (Thread[Thread-458,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:50706_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 319311 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 0x157d56ebf6f0007, 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> 319311 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@125e9408{/solr,null,UNAVAILABLE}
[junit4] 2> 319314 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[EF326B30FE0BA291]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50698 50698
[junit4] 2> 319317 INFO (Thread-441) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50698 50698
[junit4] 2> 319320 WARN (Thread-441) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/clusterprops.json
[junit4] 2> 15 /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> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/live_nodes
[junit4] 2> 15 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/overseer/collection-queue-work
[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> 319321 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[EF326B30FE0BA291]-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_EF326B30FE0BA291-001
[junit4] 2> Oct 18, 2016 1:36:33 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=836, maxMBSortInHeap=5.7493135958015396, sim=ClassicSimilarity, locale=ro, timezone=America/Nome
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=324967408,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [ConcurrentDeleteAndCreateCollectionTest, UniqFieldsUpdateProcessorFactoryTest, AssignTest, TestFieldCacheReopen, TestStressReorder, DocValuesMultiTest, DocumentAnalysisRequestHandlerTest, ForceLeaderTest, TestDocTermOrds, TestCustomSort, SoftAutoCommitTest, TestConfigReload, TestCharFilters, TestHashPartitioner, TestRandomRequestDistribution, NumericFieldsTest, TestCloudSchemaless, TestStressVersions, SchemaVersionSpecificBehaviorTest, TestNamedUpdateProcessors, TestAuthorizationFramework, TestSolrCloudWithDelegationTokens]
[junit4] Completed [62/638 (1!)] on J0 in 6.69s, 8 tests, 1 failure <<< FAILURES!
[...truncated 62240 lines...]