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 2017/01/25 20:45:14 UTC

[JENKINS] Lucene-Solr-6.x-Solaris (64bit/jdk1.8.0) - Build # 634 - Still unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/634/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenCancelFail

Error Message:
expected:<200> but was:<404>

Stack Trace:
java.lang.AssertionError: expected:<200> but was:<404>
	at __randomizedtesting.SeedInfo.seed([4785D7EF93182F22:2F3AE2C543823DCE]: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.security.hadoop.TestDelegationWithHadoopAuth.cancelDelegationToken(TestDelegationWithHadoopAuth.java:128)
	at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenCancelFail(TestDelegationWithHadoopAuth.java:280)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11261 lines...]
   [junit4] Suite: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_4785D7EF93182F22-001/init-core-data-001
   [junit4]   2> 1080099 INFO  (SUITE-TestDelegationWithHadoopAuth-seed#[4785D7EF93182F22]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1080100 INFO  (SUITE-TestDelegationWithHadoopAuth-seed#[4785D7EF93182F22]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_4785D7EF93182F22-001/tempDir-001
   [junit4]   2> 1080100 INFO  (SUITE-TestDelegationWithHadoopAuth-seed#[4785D7EF93182F22]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1080104 INFO  (Thread-4345) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1080104 INFO  (Thread-4345) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1080200 INFO  (SUITE-TestDelegationWithHadoopAuth-seed#[4785D7EF93182F22]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:57006
   [junit4]   2> 1080211 INFO  (jetty-launcher-1230-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1080211 INFO  (jetty-launcher-1230-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1080214 INFO  (jetty-launcher-1230-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@283e55ea{/solr,null,AVAILABLE}
   [junit4]   2> 1080214 INFO  (jetty-launcher-1230-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d7e6549{/solr,null,AVAILABLE}
   [junit4]   2> 1080218 INFO  (jetty-launcher-1230-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@44d96451{HTTP/1.1,[http/1.1]}{127.0.0.1:58938}
   [junit4]   2> 1080218 INFO  (jetty-launcher-1230-thread-2) [    ] o.e.j.s.Server Started @1083445ms
   [junit4]   2> 1080218 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58938}
   [junit4]   2> 1080218 ERROR (jetty-launcher-1230-thread-2) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1080218 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.5.0
   [junit4]   2> 1080218 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1080218 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1080218 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-25T19:48:03.591Z
   [junit4]   2> 1080219 INFO  (jetty-launcher-1230-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@898d274{HTTP/1.1,[http/1.1]}{127.0.0.1:62921}
   [junit4]   2> 1080219 INFO  (jetty-launcher-1230-thread-1) [    ] o.e.j.s.Server Started @1083446ms
   [junit4]   2> 1080219 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62921}
   [junit4]   2> 1080219 ERROR (jetty-launcher-1230-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1080220 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.5.0
   [junit4]   2> 1080220 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1080220 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1080220 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-25T19:48:03.593Z
   [junit4]   2> 1080223 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1080224 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1080232 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1080233 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57006/solr
   [junit4]   2> 1080234 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1080235 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57006/solr
   [junit4]   2> 1080273 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62921_solr
   [junit4]   2> 1080275 INFO  (jetty-launcher-1230-thread-2) [n:127.0.0.1:58938_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58938_solr
   [junit4]   2> 1080275 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.c.Overseer Overseer (id=97345449720414213-127.0.0.1:62921_solr-n_0000000000) starting
   [junit4]   2> 1080283 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62921_solr
   [junit4]   2> 1080378 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.c.CoreContainer Authentication plugin class obtained from ZK: org.apache.solr.security.HadoopAuthPlugin
   [junit4]   2> 1080384 INFO  (zkCallback-1240-thread-1-processing-n:127.0.0.1:62921_solr) [n:127.0.0.1:62921_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1080384 INFO  (zkCallback-1241-thread-1-processing-n:127.0.0.1:58938_solr) [n:127.0.0.1:58938_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1080386 INFO  (zkCallback-1241-thread-2-processing-n:127.0.0.1:58938_solr) [n:127.0.0.1:58938_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1080387 INFO  (OverseerStateUpdate-97345449720414213-127.0.0.1:62921_solr-n_0000000000) [n:127.0.0.1:62921_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1080388 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper}
   [junit4]   2> 1080473 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.c.f.i.CuratorFrameworkImpl Starting
   [junit4]   2> 1080523 INFO  (jetty-launcher-1230-thread-1-EventThread) [n:127.0.0.1:62921_solr    ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
   [junit4]   2> 1080559 INFO  (jetty-launcher-1230-thread-2) [n:127.0.0.1:58938_solr    ] o.a.s.c.CoreContainer Authentication plugin class obtained from ZK: org.apache.solr.security.HadoopAuthPlugin
   [junit4]   2> 1080560 INFO  (jetty-launcher-1230-thread-2) [n:127.0.0.1:58938_solr    ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper}
   [junit4]   2> 1080560 INFO  (jetty-launcher-1230-thread-2) [n:127.0.0.1:58938_solr    ] o.a.c.f.i.CuratorFrameworkImpl Starting
   [junit4]   2> 1080564 INFO  (jetty-launcher-1230-thread-2-EventThread) [n:127.0.0.1:58938_solr    ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
   [junit4]   2> 1080639 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
   [junit4]   2> 1080639 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.security.PKIAuthenticationPlugin$HttpHeaderClientConfigurer@4a259197
   [junit4]   2> 1080639 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.security.PKIAuthenticationPlugin$HttpHeaderClientConfigurer@4a259197
   [junit4]   2> 1080642 INFO  (jetty-launcher-1230-thread-2) [n:127.0.0.1:58938_solr    ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
   [junit4]   2> 1080642 INFO  (jetty-launcher-1230-thread-2) [n:127.0.0.1:58938_solr    ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.security.PKIAuthenticationPlugin$HttpHeaderClientConfigurer@4bc48d48
   [junit4]   2> 1080642 INFO  (jetty-launcher-1230-thread-2) [n:127.0.0.1:58938_solr    ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.security.PKIAuthenticationPlugin$HttpHeaderClientConfigurer@4bc48d48
   [junit4]   2> 1080652 INFO  (jetty-launcher-1230-thread-1) [n:127.0.0.1:62921_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_4785D7EF93182F22-001/tempDir-001/node1/.
   [junit4]   2> 1080654 INFO  (jetty-launcher-1230-thread-2) [n:127.0.0.1:58938_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_4785D7EF93182F22-001/tempDir-001/node2/.
   [junit4]   2> 1080904 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
   [junit4]   2> 1080909 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1080911 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1080936 INFO  (qtp1642305584-10073) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params op=someSolrOperation&action=LIST&user.name=foo&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1080936 INFO  (qtp1642305584-10073) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={op=someSolrOperation&action=LIST&user.name=foo&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1080941 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=foo&p=foo&t=simple-dt&e=1485409684298&s=hT6tbOFmhvSqlW5hXwbNBXXUBzw="; Domain=127.0.0.1; Expires=jeu., 26-janv.-2017 05:48:04 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 26-janv.-2017 05:48:04 GMT
   [junit4]   2> 1080942 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
   [junit4]   2> 1080964 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancelFail-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
   [junit4]   2> 1081066 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancelFail-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestDelegationWithHadoopAuth -Dtests.method=testDelegationTokenCancelFail -Dtests.seed=4785D7EF93182F22 -Dtests.slow=true -Dtests.locale=fr-CA -Dtests.timezone=America/Bahia -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 0.13s J1 | TestDelegationWithHadoopAuth.testDelegationTokenCancelFail <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<200> but was:<404>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([4785D7EF93182F22:2F3AE2C543823DCE]:0)
   [junit4]    > 	at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.cancelDelegationToken(TestDelegationWithHadoopAuth.java:128)
   [junit4]    > 	at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenCancelFail(TestDelegationWithHadoopAuth.java:280)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1081117 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
   [junit4]   2> 1081141 INFO  (qtp1642305584-10072) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7sMigFZ-zg_DAIDFK8Lm5Ug0IzL6WCUtM0uWqTy47KoB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081141 INFO  (qtp1642305584-10072) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7sMigFZ-zg_DAIDFK8Lm5Ug0IzL6WCUtM0uWqTy47KoB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081145 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081146 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081149 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081153 INFO  (qtp1771910424-10065) [n:127.0.0.1:58938_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7sMigFZ-zg_DAIDFK8Lm5Ug0IzL6WCUtM0uWqTy47KoB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081153 INFO  (qtp1771910424-10065) [n:127.0.0.1:58938_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7sMigFZ-zg_DAIDFK8Lm5Ug0IzL6WCUtM0uWqTy47KoB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081155 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081174 INFO  (qtp1642305584-10073) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7syigFZ-zg_MgMDFPZCl4HDsfnPM6WiKAxF5x9I9S2IB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081174 INFO  (qtp1642305584-10073) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7syigFZ-zg_MgMDFPZCl4HDsfnPM6WiKAxF5x9I9S2IB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081176 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081180 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081181 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081183 INFO  (qtp1642305584-10073) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7syigFZ-zg_MgMDFPZCl4HDsfnPM6WiKAxF5x9I9S2IB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081184 INFO  (qtp1642305584-10073) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7syigFZ-zg_MgMDFPZCl4HDsfnPM6WiKAxF5x9I9S2IB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081188 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081190 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081192 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081209 INFO  (qtp1642305584-10078) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7tYigFZ-zg_WAQDFIFg89eWvpR21dIWkOOV5HoPbLWFB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081209 INFO  (qtp1642305584-10078) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7tYigFZ-zg_WAQDFIFg89eWvpR21dIWkOOV5HoPbLWFB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081213 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081214 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081216 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081221 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081223 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081225 INFO  (qtp1642305584-10074) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7tYigFZ-zg_WAQDFIFg89eWvpR21dIWkOOV5HoPbLWFB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081225 INFO  (qtp1642305584-10074) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7tYigFZ-zg_WAQDFIFg89eWvpR21dIWkOOV5HoPbLWFB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081228 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081242 INFO  (qtp1642305584-10077) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7t7igFZ-zg_ewUDFFP-UJGiJuMhJAse2KrCGsxQHSzIB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081242 INFO  (qtp1642305584-10077) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7t7igFZ-zg_ewUDFFP-UJGiJuMhJAse2KrCGsxQHSzIB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081244 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081248 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081250 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081252 INFO  (qtp1642305584-10079) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7t7igFZ-zg_ewUDFFP-UJGiJuMhJAse2KrCGsxQHSzIB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081252 INFO  (qtp1642305584-10079) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7t7igFZ-zg_ewUDFFP-UJGiJuMhJAse2KrCGsxQHSzIB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081255 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081256 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
   [junit4]   2> 1081283 INFO  (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
   [junit4]   2> 1081294 INFO  (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
   [junit4]   2> 1081323 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
   [junit4]   2> 1081335 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081336 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081337 INFO  (qtp1642305584-10077) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7vbigFZ-zg_2wcDFC7t0f8LRQnGP5_TEz1IqS7PZUhjB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081338 INFO  (qtp1642305584-10077) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7vbigFZ-zg_2wcDFC7t0f8LRQnGP5_TEz1IqS7PZUhjB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081343 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081345 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081347 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081351 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081353 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081355 INFO  (qtp1642305584-10074) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7vbigFZ-zg_2wcDFC7t0f8LRQnGP5_TEz1IqS7PZUhjB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081355 INFO  (qtp1642305584-10074) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7vbigFZ-zg_2wcDFC7t0f8LRQnGP5_TEz1IqS7PZUhjB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081360 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081361 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081363 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081365 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
   [junit4]   2> 1081396 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
   [junit4]   2> 1081411 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081413 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081415 ERROR (qtp1642305584-10078) [n:127.0.0.1:62921_solr    ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_8]
   [junit4]   2> 1081422 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081427 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081428 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081430 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081446 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081448 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081450 ERROR (qtp1642305584-10074) [n:127.0.0.1:62921_solr    ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_9]
   [junit4]   2> 1081457 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1081459 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57006/solr ready
   [junit4]   2> 1081461 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081464 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081465 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
   [junit4]   2> 1081490 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
   [junit4]   2> 1081502 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081504 INFO  (qtp1642305584-10080) [n:127.0.0.1:62921_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7yFigFZ-zhAhQoDFJe1DWC3-mwH-Q9kCO80FRQ_WQrGB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1081504 INFO  (qtp1642305584-10080) [n:127.0.0.1:62921_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAVnXK7yFigFZ-zhAhQoDFJe1DWC3-mwH-Q9kCO80FRQ_WQrGB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1081505 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
   [junit4]   2> 1081535 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
   [junit4]   2> 1081546 WARN  (qtp1642305584-10073) [n:127.0.0.1:62921_solr    ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar tries to renew a token with renewer foo
   [junit4]   2> 1081547 WARN  (qtp1642305584-10073) [n:127.0.0.1:62921_solr    ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1081547 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081549 WARN  (qtp1771910424-10063) [    ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar tries to renew a token with renewer foo
   [junit4]   2> 1081549 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081549 WARN  (qtp1771910424-10063) [    ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1081560 WARN  (qtp1642305584-10078) [n:127.0.0.1:62921_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> 1081561 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081561 WARN  (qtp1642305584-10078) [n:127.0.0.1:62921_solr    ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1081564 WARN  (qtp1771910424-10066) [    ] 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> 1081564 WARN  (qtp1771910424-10066) [    ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
   [junit4]   2> 1081564 WARN  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[4785D7EF93182F22]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=jeu., 01-janv.-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: jeu., 01-janv.-1970 00:00:00 GMT
   [junit4]   2> 1081565 INFO  (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[4785D7EF93182F22]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
   [junit4]   2> 1081567 INFO  (jetty-launcher-1230-thread-1) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@44d96451{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1081567 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2140763823
   [junit4]   2> 1081570 INFO  (jetty-launcher-1230-thread-2) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@898d274{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1081571 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1186326535
   [junit4]   2> 1081582 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.c.Overseer Overseer (id=97345449720414213-127.0.0.1:62921_solr-n_0000000000) closing
   [junit4]   2> 1081582 INFO  (OverseerStateUpdate-97345449720414213-127.0.0.1:62921_solr-n_0000000000) [n:127.0.0.1:62921_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:62921_solr
   [junit4]   2> 1081582 INFO  (zkCallback-1240-thread-3-processing-n:127.0.0.1:62921_solr) [n:127.0.0.1:62921_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 1081583 INFO  (jetty-launcher-1230-thread-2) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1081584 ERROR (Thread[Thread-4362,5,TGRP-TestDelegationWithHadoopAuth]) [n:127.0.0.1:62921_solr    ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
   [junit4]   2> 1081595 INFO  (jetty-launcher-1230-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1d7e6549{/solr,null,UNAVAILABLE}
   [junit4]   2> 1083081 WARN  (zkCallback-1241-thread-2-processing-n:127.0.0.1:58938_solr) [n:127.0.0.1:58938_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1083082 INFO  (jetty-launcher-1230-thread-1) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1083082 ERROR (Thread[Thread-4364,5,TGRP-TestDelegationWithHadoopAuth]) [n:127.0.0.1:58938_solr    ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
   [junit4]   2> 1083087 INFO  (jetty-launcher-1230-thread-1) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@283e55ea{/solr,null,UNAVAILABLE}
   [junit4]   2> 1083089 INFO  (SUITE-TestDelegationWithHadoopAuth-seed#[4785D7EF93182F22]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57006 57006
   [junit4]   2> 1083126 INFO  (Thread-4345) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57006 57006
   [junit4]   2> 1083127 WARN  (Thread-4345) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	17	/solr/aliases.json
   [junit4]   2> 	17	/solr/clusterprops.json
   [junit4]   2> 	2	/solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	17	/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> 	17	/solr/live_nodes
   [junit4]   2> 	17	/solr/collections
   [junit4]   2> 	3	/solr/overseer/queue
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
   [junit4]   2> 	2	/solr/overseer/queue-work
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
   [junit4]   2> 
   [junit4]   2> 1083128 INFO  (SUITE-TestDelegationWithHadoopAuth-seed#[4785D7EF93182F22]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_4785D7EF93182F22-001
   [junit4]   2> Jan 25, 2017 7:48:06 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=1670, maxMBSortInHeap=5.125795871975305, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=fr-CA, timezone=America/Bahia
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=3,threads=1,free=62429688,total=402653184
   [junit4]   2> NOTE: All tests run in this JVM: [SimpleMLTQParserTest, BooleanFieldTest, DistributedDebugComponentTest, TestQuerySenderNoQuery, TestJmxMonitoredMap, MetricsHandlerTest, TestSolrQueryParser, TestTrackingShardHandlerFactory, SolrCoreCheckLockOnStartupTest, ResponseHeaderTest, TestReqParamsAPI, TestStressUserVersions, URLClassifyProcessorTest, CoreAdminCreateDiscoverTest, OverseerTaskQueueTest, TestSolr4Spatial2, SpatialHeatmapFacetsTest, TestSQLHandler, TestHdfsUpdateLog, DistanceFunctionTest, HdfsDirectoryTest, SSLMigrationTest, TestMacroExpander, DistribJoinFromCollectionTest, BasicAuthIntegrationTest, BlockDirectoryTest, RestartWhileUpdatingTest, DeleteInactiveReplicaTest, StatsComponentTest, TestExpandComponent, RegexBoostProcessorTest, TestXmlQParser, TestCollectionAPI, TestJoin, ShardSplitTest, WordBreakSolrSpellCheckerTest, SpellPossibilityIteratorTest, ClassificationUpdateProcessorTest, TestGraphTermsQParserPlugin, TestDFISimilarityFactory, CollectionsAPIDistributedZkTest, TestCoreDiscovery, CacheHeaderTest, SolrCLIZkUtilsTest, TestClusterStateMutator, DefaultValueUpdateProcessorTest, CSVRequestHandlerTest, CheckHdfsIndexTest, TestSuggestSpellingConverter, SortByFunctionTest, RecoveryAfterSoftCommitTest, TestJsonRequest, ForceLeaderTest, OverseerRolesTest, TestMissingGroups, ClassificationUpdateProcessorFactoryTest, ShardRoutingCustomTest, AnalyticsMergeStrategyTest, TestIndexingPerformance, DebugComponentTest, TestSearchPerf, SuggesterTest, TestFiltering, LeaderFailureAfterFreshStartTest, TestConfigSetImmutable, BlockJoinFacetDistribTest, TestInitQParser, TestDelegationWithHadoopAuth]
   [junit4] Completed [172/681 (1!)] on J1 in 3.55s, 8 tests, 1 failure <<< FAILURES!

[...truncated 64154 lines...]