Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20698/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseParallelGC
3 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth: 1)
Thread[id=9821, name=jetty-launcher-1038-thread-2-EventThread,
state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth] at
sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth:
1) Thread[id=9821, name=jetty-launcher-1038-thread-2-EventThread,
state=TIMED_WAITING, group=TGRP-TestImpersonationWithHadoopAuth]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
at __randomizedtesting.SeedInfo.seed([B85F2B438F46BB19]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.client.solrj.io.graph.GraphExpressionTest
Error Message:
There are still nodes recoverying - waited for 30 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 30
seconds
at __randomizedtesting.SeedInfo.seed([5A76A4E7F58DD8BD]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:185)
at
org.apache.solr.client.solrj.io.graph.GraphExpressionTest.setupCluster(GraphExpressionTest.java:88)
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:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:748)
FAILED:
org.apache.solr.cloud.TestTlogReplica.testOutOfOrderDBQWithInPlaceUpdates
Error Message:
Can not find doc 1 in http://127.0.0.1:41869/solr
Stack Trace:
java.lang.AssertionError: Can not find doc 1 in http://127.0.0.1:41869/solr
at
__randomizedtesting.SeedInfo.seed([B85F2B438F46BB19:3E9ED3AED0176DF9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:868)
at
org.apache.solr.cloud.TestTlogReplica.testOutOfOrderDBQWithInPlaceUpdates(TestTlogReplica.java:671)
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:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:748)
Build Log:
[...truncated 12331 lines...]
[junit4] Suite:
org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth
[junit4] 2> 739623 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_B85F2B438F46BB19-001/init-core-data-001
[junit4] 2> 739624 WARN
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 739624 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 739625 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 739641 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_B85F2B438F46BB19-001/tempDir-001
[junit4] 2> 739641 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 739641 INFO (Thread-4736) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 739641 INFO (Thread-4736) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 739642 ERROR (Thread-4736) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 739741 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:36717
[junit4] 2> 739746 INFO (jetty-launcher-1038-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 739748 INFO (jetty-launcher-1038-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 739752 INFO (jetty-launcher-1038-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@ca2dfac{/solr,null,AVAILABLE}
[junit4] 2> 739752 INFO (jetty-launcher-1038-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1081c0e0{/solr,null,AVAILABLE}
[junit4] 2> 739752 INFO (jetty-launcher-1038-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3c0662b2{SSL,[ssl,
http/1.1]}{127.0.0.1:37689}
[junit4] 2> 739752 INFO (jetty-launcher-1038-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@2208b016{SSL,[ssl,
http/1.1]}{127.0.0.1:34255}
[junit4] 2> 739752 INFO (jetty-launcher-1038-thread-1) [ ]
o.e.j.s.Server Started @743420ms
[junit4] 2> 739752 INFO (jetty-launcher-1038-thread-2) [ ]
o.e.j.s.Server Started @743420ms
[junit4] 2> 739752 INFO (jetty-launcher-1038-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37689}
[junit4] 2> 739752 INFO (jetty-launcher-1038-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34255}
[junit4] 2> 739752 ERROR (jetty-launcher-1038-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 739752 ERROR (jetty-launcher-1038-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 739754 INFO (jetty-launcher-1038-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 739754 INFO (jetty-launcher-1038-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 739754 INFO (jetty-launcher-1038-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 739754 INFO (jetty-launcher-1038-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-20T00:03:15.987Z
[junit4] 2> 739755 INFO (jetty-launcher-1038-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 739755 INFO (jetty-launcher-1038-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 739755 INFO (jetty-launcher-1038-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 739755 INFO (jetty-launcher-1038-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-20T00:03:15.988Z
[junit4] 2> 739775 INFO (jetty-launcher-1038-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 739781 INFO (jetty-launcher-1038-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 739787 INFO (jetty-launcher-1038-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36717/solr
[junit4] 2> 739801 INFO (jetty-launcher-1038-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36717/solr
[junit4] 2> 739896 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 739896 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 739897 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:34255_solr
[junit4] 2> 739897 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37689_solr
[junit4] 2> 739897 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.c.Overseer Overseer
(id=98858290110464006-127.0.0.1:34255_solr-n_0000000000) starting
[junit4] 2> 739905 INFO
(zkCallback-1049-thread-1-processing-n:127.0.0.1:37689_solr)
[n:127.0.0.1:37689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 739905 INFO
(zkCallback-1050-thread-1-processing-n:127.0.0.1:34255_solr)
[n:127.0.0.1:34255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 739913 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34255_solr
[junit4] 2> 739921 INFO
(zkCallback-1050-thread-1-processing-n:127.0.0.1:34255_solr)
[n:127.0.0.1:34255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 739921 INFO
(zkCallback-1049-thread-1-processing-n:127.0.0.1:37689_solr)
[n:127.0.0.1:37689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 739960 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 739962 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.s.HadoopAuthPlugin Params:
{proxyuser.anyHostUsersGroup.hosts=*, proxyuser.wrongHost.hosts=1.1.1.1.1.1,
proxyuser.bogusGroup.groups=__some_bogus_group,
proxyuser.localHostAnyGroup.groups=*, cookie.domain=127.0.0.1,
proxyuser.wrongHost.groups=*, type=simple, proxyuser.noHosts.groups=*,
proxyuser.anyHostUsersGroup.groups=jenkins, proxyuser.noGroups.hosts=*,
proxyuser.anyHostAnyUser.groups=*, delegation-token.token-kind=solr-dt,
proxyuser.bogusGroup.hosts=*,
zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm,
signer.secret.provider.zookeeper.path=/token, zk-dt-secret-manager.enable=true,
proxyuser.localHostAnyGroup.hosts=localhost.localdomain,localhost,127.0.0.1,
proxyuser.anyHostAnyUser.hosts=*, signer.secret.provider=zookeeper}
[junit4] 2> 739964 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 739966 INFO (jetty-launcher-1038-thread-1-EventThread)
[n:127.0.0.1:37689_solr ] o.a.c.f.s.ConnectionStateManager State change:
CONNECTED
[junit4] 2> 739992 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 739993 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.s.HadoopAuthPlugin Params:
{proxyuser.anyHostUsersGroup.hosts=*, proxyuser.wrongHost.hosts=1.1.1.1.1.1,
proxyuser.bogusGroup.groups=__some_bogus_group,
proxyuser.localHostAnyGroup.groups=*, cookie.domain=127.0.0.1,
proxyuser.wrongHost.groups=*, type=simple, proxyuser.noHosts.groups=*,
proxyuser.anyHostUsersGroup.groups=jenkins, proxyuser.noGroups.hosts=*,
proxyuser.anyHostAnyUser.groups=*, delegation-token.token-kind=solr-dt,
proxyuser.bogusGroup.hosts=*,
zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm,
signer.secret.provider.zookeeper.path=/token, zk-dt-secret-manager.enable=true,
proxyuser.localHostAnyGroup.hosts=localhost.localdomain,localhost,127.0.0.1,
proxyuser.anyHostAnyUser.hosts=*, signer.secret.provider=zookeeper}
[junit4] 2> 739993 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 739994 INFO (jetty-launcher-1038-thread-2-EventThread)
[n:127.0.0.1:34255_solr ] o.a.c.f.s.ConnectionStateManager State change:
CONNECTED
[junit4] 2> 740002 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is
managing internode requests
[junit4] 2> 740024 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34255.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a7190cf
[junit4] 2> 740029 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34255.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a7190cf
[junit4] 2> 740029 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34255.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a7190cf
[junit4] 2> 740030 INFO (jetty-launcher-1038-thread-2)
[n:127.0.0.1:34255_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_B85F2B438F46BB19-001/tempDir-001/node2/.
[junit4] 2> 740973 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is
managing internode requests
[junit4] 2> 740992 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37689.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a7190cf
[junit4] 2> 740998 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37689.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a7190cf
[junit4] 2> 740998 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37689.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a7190cf
[junit4] 2> 740998 INFO (jetty-launcher-1038-thread-1)
[n:127.0.0.1:37689_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestImpersonationWithHadoopAuth_B85F2B438F46BB19-001/tempDir-001/node1/.
[junit4] 2> 741015 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 741015 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36717/solr ready
[junit4] 2> 741025 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyInvalidGroup-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidGroup
[junit4] 2> 741060 WARN (qtp878269674-9771) [ ]
o.a.h.s.ShellBasedUnixGroupsMapping got exception trying to get groups for user
bar: id: ‘bar’: no such user
[junit4] 2> id: ‘bar’: no such user
[junit4] 2>
[junit4] 2> 741061 WARN
(TEST-TestImpersonationWithHadoopAuth.testProxyInvalidGroup-seed#[B85F2B438F46BB19])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=bogusGroup&p=bogusGroup&t=simple-dt&e=1508493797283&s=MH5QDz5GA4W4d8K/KQn7zZcgZAI=";
Domain=127.0.0.1; Expires=vie, 20-oct-2017 10:03:17 GMT; Secure; HttpOnly".
Invalid 'expires' attribute: vie, 20-oct-2017 10:03:17 GMT
[junit4] 2> 741062 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyInvalidGroup-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidGroup
[junit4] 2> 741072 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyNullProxyUser-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNullProxyUser
[junit4] 2> 741074 INFO (qtp878269674-9781) [ ]
o.a.s.s.SolrDispatchFilter Error authenticating
[junit4] 2> java.lang.IllegalArgumentException: userName is NULL, empty or
contains a '&'
[junit4] 2> at
org.apache.hadoop.security.authentication.util.AuthToken.checkForIllegalArgument(AuthToken.java:87)
[junit4] 2> at
org.apache.hadoop.security.authentication.util.AuthToken.<init>(AuthToken.java:70)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.AuthenticationToken.<init>(AuthenticationToken.java:58)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.PseudoAuthenticationHandler.authenticate(PseudoAuthenticationHandler.java:195)
[junit4] 2> at
org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.authenticate(DelegationTokenAuthenticationHandler.java:348)
[junit4] 2> at
org.apache.solr.security.RequestContinuesRecorderAuthenticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandler.java:69)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.AuthenticationFilter.doFilter(AuthenticationFilter.java:518)
[junit4] 2> at
org.apache.solr.security.HadoopAuthFilter.doFilter(HadoopAuthFilter.java:110)
[junit4] 2> at
org.apache.solr.security.HadoopAuthPlugin.doAuthenticate(HadoopAuthPlugin.java:245)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:451)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 741075 WARN (qtp878269674-9781) [ ] o.e.j.s.ServletHandler
/solr/admin/collections
[junit4] 2> org.apache.solr.common.SolrException: Error during request
authentication,
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:457)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.IllegalArgumentException: userName is
NULL, empty or contains a '&'
[junit4] 2> at
org.apache.hadoop.security.authentication.util.AuthToken.checkForIllegalArgument(AuthToken.java:87)
[junit4] 2> at
org.apache.hadoop.security.authentication.util.AuthToken.<init>(AuthToken.java:70)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.AuthenticationToken.<init>(AuthenticationToken.java:58)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.PseudoAuthenticationHandler.authenticate(PseudoAuthenticationHandler.java:195)
[junit4] 2> at
org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.authenticate(DelegationTokenAuthenticationHandler.java:348)
[junit4] 2> at
org.apache.solr.security.RequestContinuesRecorderAuthenticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandler.java:69)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.AuthenticationFilter.doFilter(AuthenticationFilter.java:518)
[junit4] 2> at
org.apache.solr.security.HadoopAuthFilter.doFilter(HadoopAuthFilter.java:110)
[junit4] 2> at
org.apache.solr.security.HadoopAuthPlugin.doAuthenticate(HadoopAuthPlugin.java:245)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:451)
[junit4] 2> ... 29 more
[junit4] 2> 741076 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyNullProxyUser-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNullProxyUser
[junit4] 2> 741086 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateHost-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateHost
[junit4] 2> 741089 INFO (qtp878269674-9773) [n:127.0.0.1:34255_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
doAs=bar&action=LIST&user.name=localHostAnyGroup&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 741089 INFO (qtp878269674-9773) [n:127.0.0.1:34255_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={doAs=bar&action=LIST&user.name=localHostAnyGroup&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 741090 WARN
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateHost-seed#[B85F2B438F46BB19])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=localHostAnyGroup&p=localHostAnyGroup&t=simple-dt&e=1508493797321&s=l1Mu5ewHBCiVHdTtH38yahQdX9A=";
Domain=127.0.0.1; Expires=vie, 20-oct-2017 10:03:17 GMT; Secure; HttpOnly".
Invalid 'expires' attribute: vie, 20-oct-2017 10:03:17 GMT
[junit4] 2> 741090 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateHost-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateHost
[junit4] 2> 741102 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyInvalidProxyUser-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidProxyUser
[junit4] 2> 741105 WARN
(TEST-TestImpersonationWithHadoopAuth.testProxyInvalidProxyUser-seed#[B85F2B438F46BB19])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=bar&p=bar&t=simple-dt&e=1508493797337&s=q/si9pY6DmTaPyVSJB5k2QuOm50=";
Domain=127.0.0.1; Expires=vie, 20-oct-2017 10:03:17 GMT; Secure; HttpOnly".
Invalid 'expires' attribute: vie, 20-oct-2017 10:03:17 GMT
[junit4] 2> 741106 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyInvalidProxyUser-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidProxyUser
[junit4] 2> 741116 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyWrongHost-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyWrongHost
[junit4] 2> 756135 WARN
(jetty-launcher-1038-thread-1-SendThread(127.0.0.1:36717))
[n:127.0.0.1:37689_solr ] o.a.z.ClientCnxn Client session timed out, have
not heard from server in 15136ms for sessionid 0x15f371751230005
[junit4] 2> 756135 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 0x15f371751230005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 756135 WARN
(jetty-launcher-1038-thread-2-SendThread(127.0.0.1:36717))
[n:127.0.0.1:34255_solr ] o.a.z.ClientCnxn Client session timed out, have
not heard from server in 16105ms for sessionid 0x15f371751230006
[junit4] 2> 756135 WARN
(jetty-launcher-1038-thread-2-SendThread(127.0.0.1:36717))
[n:127.0.0.1:34255_solr ] o.a.z.ClientCnxn Client session timed out, have
not heard from server in 15156ms for sessionid 0x15f371751230008
[junit4] 2> 756135 WARN
(jetty-launcher-1038-thread-1-SendThread(127.0.0.1:36717))
[n:127.0.0.1:37689_solr ] o.a.z.ClientCnxn Client session timed out, have
not heard from server in 15156ms for sessionid 0x15f371751230007
[junit4] 2> 756136 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 0x15f371751230006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 756136 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 0x15f371751230008, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 756136 WARN
(TEST-TestImpersonationWithHadoopAuth.testProxyWrongHost-seed#[B85F2B438F46BB19])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=wrongHost&p=wrongHost&t=simple-dt&e=1508493797351&s=uKtVjM3Pscs9Nrt31h0H+TN3YGQ=";
Domain=127.0.0.1; Expires=vie, 20-oct-2017 10:03:17 GMT; Secure; HttpOnly".
Invalid 'expires' attribute: vie, 20-oct-2017 10:03:17 GMT
[junit4] 2> 756136 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 0x15f371751230007, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 756136 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyWrongHost-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyWrongHost
[junit4] IGNOR/A 0.00s J0 | TestImpersonationWithHadoopAuth.testForwarding
[junit4] > Assumption #1: 'awaitsfix' test group is disabled
(@AwaitsFix(bugUrl=https://issues.apache.org/jira/browse/HADOOP-9893))
[junit4] 2> 756144 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigHosts-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNoConfigHosts
[junit4] 2> 756148 WARN
(TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigHosts-seed#[B85F2B438F46BB19])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=noHosts&p=noHosts&t=simple-dt&e=1508493812379&s=ClM9XPymZ1UxbASwaM9YffB90So=";
Domain=127.0.0.1; Expires=vie, 20-oct-2017 10:03:32 GMT; Secure; HttpOnly".
Invalid 'expires' attribute: vie, 20-oct-2017 10:03:32 GMT
[junit4] 2> 756148 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigHosts-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNoConfigHosts
[junit4] 2> 756158 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateGroup-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateGroup
[junit4] 2> 756161 INFO (qtp878269674-9779) [n:127.0.0.1:34255_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
doAs=jenkins&action=LIST&user.name=anyHostUsersGroup&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 756161 INFO (qtp878269674-9779) [n:127.0.0.1:34255_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={doAs=jenkins&action=LIST&user.name=anyHostUsersGroup&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 756161 WARN
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateGroup-seed#[B85F2B438F46BB19])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=anyHostUsersGroup&p=anyHostUsersGroup&t=simple-dt&e=1508493812393&s=4zYk4FdZ2hXdt2UHoxX7no3EZD0=";
Domain=127.0.0.1; Expires=vie, 20-oct-2017 10:03:32 GMT; Secure; HttpOnly".
Invalid 'expires' attribute: vie, 20-oct-2017 10:03:32 GMT
[junit4] 2> 756161 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateGroup-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateGroup
[junit4] 2> 756171 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigGroups-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNoConfigGroups
[junit4] 2> 756174 WARN
(TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigGroups-seed#[B85F2B438F46BB19])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=noGroups&p=noGroups&t=simple-dt&e=1508493812406&s=F0ao2SQe5/yeleXrnU6L8oVzPZU=";
Domain=127.0.0.1; Expires=vie, 20-oct-2017 10:03:32 GMT; Secure; HttpOnly".
Invalid 'expires' attribute: vie, 20-oct-2017 10:03:32 GMT
[junit4] 2> 756175 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyNoConfigGroups-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNoConfigGroups
[junit4] 2> 756184 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateAnyHostAnyUser-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateAnyHostAnyUser
[junit4] 2> 756187 INFO (qtp878269674-9775) [n:127.0.0.1:34255_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
doAs=bar&action=LIST&user.name=anyHostAnyUser&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 756187 INFO (qtp878269674-9775) [n:127.0.0.1:34255_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={doAs=bar&action=LIST&user.name=anyHostAnyUser&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 756187 WARN
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateAnyHostAnyUser-seed#[B85F2B438F46BB19])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=anyHostAnyUser&p=anyHostAnyUser&t=simple-dt&e=1508493812419&s=yQUmplTh0Gd8SHkFoh+1cf+FcwE=";
Domain=127.0.0.1; Expires=vie, 20-oct-2017 10:03:32 GMT; Secure; HttpOnly".
Invalid 'expires' attribute: vie, 20-oct-2017 10:03:32 GMT
[junit4] 2> 756187 INFO
(TEST-TestImpersonationWithHadoopAuth.testProxyValidateAnyHostAnyUser-seed#[B85F2B438F46BB19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateAnyHostAnyUser
[junit4] 2> 756236 INFO (jetty-launcher-1038-thread-2-EventThread)
[n:127.0.0.1:34255_solr ] o.a.c.f.s.ConnectionStateManager State change:
SUSPENDED
[junit4] 2> 756236 WARN
(zkCallback-1050-thread-1-processing-n:127.0.0.1:34255_solr)
[n:127.0.0.1:34255_solr ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@53e315bb name:
ZooKeeperConnection Watcher:127.0.0.1:36717/solr got event WatchedEvent
state:Disconnected type:None path:null path: null type: None
[junit4] 2> 756236 WARN
(zkCallback-1050-thread-1-processing-n:127.0.0.1:34255_solr)
[n:127.0.0.1:34255_solr ] o.a.s.c.c.ConnectionManager zkClient has
disconnected
[junit4] 2> 756237 WARN
(zkCallback-1049-thread-3-processing-n:127.0.0.1:37689_solr)
[n:127.0.0.1:37689_solr ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6d05f5f9 name:
ZooKeeperConnection Watcher:127.0.0.1:36717/solr got event WatchedEvent
state:Disconnected type:None path:null path: null type: None
[junit4] 2> 756238 WARN
(zkCallback-1049-thread-3-processing-n:127.0.0.1:37689_solr)
[n:127.0.0.1:37689_solr ] o.a.s.c.c.ConnectionManager zkClient has
disconnected
[junit4] 2> 756242 INFO (jetty-closer-1039-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@3c0662b2{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 756243 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=261437608
[junit4] 2> 756243 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 756243 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1f11f0e2: rootName =
solr_37689, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@67a4b4f
[junit4] 2> 756244 INFO (jetty-closer-1039-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@2208b016{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 756244 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2124458831
[junit4] 2> 756244 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 756244 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2fc04ee4: rootName =
solr_34255, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@887d012
[junit4] 2> 756247 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 756247 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@494210e3: rootName =
solr_34255, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7cacc628
[junit4] 2> 756249 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 756249 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2ede0e24: rootName =
solr_37689, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7cacc628
[junit4] 2> 756250 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 756250 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3a115149: rootName =
solr_34255, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5e13002a
[junit4] 2> 756252 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 756252 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2dacb84: rootName =
solr_37689, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5e13002a
[junit4] 2> 757331 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:34255_solr
[junit4] 2> 757331 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 757331 INFO
(zkCallback-1050-thread-4-processing-n:127.0.0.1:34255_solr)
[n:127.0.0.1:34255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 757331 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 757331 INFO (jetty-closer-1039-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98858290110464006-127.0.0.1:34255_solr-n_0000000000) closing
[junit4] 2> 757332 WARN
(OverseerAutoScalingTriggerThread-98858290110464006-127.0.0.1:34255_solr-n_0000000000)
[n:127.0.0.1:34255_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 757332 INFO
(OverseerCollectionConfigSetProcessor-98858290110464006-127.0.0.1:34255_solr-n_0000000000)
[n:127.0.0.1:34255_solr ] o.a.s.c.OverseerTaskProcessor According to ZK I
(id=98858290110464006-127.0.0.1:34255_solr-n_0000000000) am no longer a leader.
[junit4] 2> 757332 INFO
(OverseerStateUpdate-98858290110464006-127.0.0.1:34255_solr-n_0000000000)
[n:127.0.0.1:34255_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:34255_solr
[junit4] 2> 757333 ERROR
(Thread[Thread-4753,5,TGRP-TestImpersonationWithHadoopAuth])
[n:127.0.0.1:34255_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager
ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 757476 INFO
(zkCallback-1049-thread-5-processing-n:127.0.0.1:37689_solr)
[n:127.0.0.1:37689_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:37689_solr
[junit4] 2> 757477 INFO
(zkCallback-1049-thread-3-processing-n:127.0.0.1:37689_solr)
[n:127.0.0.1:37689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 757477 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:37689_solr
[junit4] 2> 757478 INFO
(zkCallback-1049-thread-3-processing-n:127.0.0.1:37689_solr)
[n:127.0.0.1:37689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 757478 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 757478 INFO (jetty-closer-1039-thread-2) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 757480 ERROR
(Thread[Thread-4756,5,TGRP-TestImpersonationWithHadoopAuth])
[n:127.0.0.1:37689_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager
ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 757981 INFO (jetty-closer-1039-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@ca2dfac{/solr,null,UNAVAILABLE}
[junit4] 2> 758010 INFO (jetty-closer-1039-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1081c0e0{/solr,null,UNAVAILABLE}
[junit4] 2> 758012 ERROR
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 758012 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:36717 36717
[junit4] 2> 763043 INFO (Thread-4736) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:36717 36717
[junit4] 2> 763043 WARN (Thread-4736) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_3
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
[junit4] 2>
[junit4] 2> 763043 INFO
(SUITE-TestImpersonationWithHadoopAuth-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> Oct 20, 2017 12:03:39 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> Oct 20, 2017 12:03:59 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth:
[junit4] 2> 1) Thread[id=9821,
name=jetty-launcher-1038-thread-2-EventThread, state=TIMED_WAITING,
group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] 2> at
java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] 2> at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] 2> at
org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] 2> at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] 2> at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] 2> at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] 2> at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] 2> at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] 2> at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] 2> at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] 2> Oct 20, 2017 12:03:59 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=9821,
name=jetty-launcher-1038-thread-2-EventThread, state=TIMED_WAITING,
group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] 2> 783127 ERROR (jetty-launcher-1038-thread-2-EventThread)
[n:127.0.0.1:34255_solr ] o.a.c.f.i.CuratorFrameworkImpl Watcher exception
[junit4] 2> java.lang.InterruptedException
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1039)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] 2> at
java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] 2> at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] 2> at
org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] 2> at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] 2> at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] 2> at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] 2> at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] 2> at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] 2> at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] 2> at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] 2> Oct 20, 2017 12:03:59 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=RandomSimilarity(queryNorm=true): {}, locale=es-UY, timezone=America/La_Paz
[junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=8,threads=1,free=228617200,total=532676608
[junit4] 2> NOTE: All tests run in this JVM: [TestFieldCache,
TestStressVersions, TestUseDocValuesAsStored2,
StatelessScriptUpdateProcessorFactoryTest, JsonLoaderTest, CdcrUpdateLogTest,
TestExpandComponent, RuleEngineTest, TestSystemCollAutoCreate,
TlogReplayBufferedWhileIndexingTest, TestConfigSetsAPI,
TestMinMaxOnMultiValuedField, DocExpirationUpdateProcessorFactoryTest,
TestRecoveryHdfs, PeerSyncTest, TestClusterProperties,
LeaderElectionContextKeyTest, TestCloudPseudoReturnFields, DeleteReplicaTest,
TestCopyFieldCollectionResource, ActionThrottleTest, TestConfigSets,
DistributedExpandComponentTest, DocValuesTest, RequestLoggingTest,
UpdateParamsTest, TestSuggestSpellingConverter,
SpellCheckCollatorWithCollapseTest, RestartWhileUpdatingTest, CacheHeaderTest,
TestReversedWildcardFilterFactory, TestFoldingMultitermQuery, CoreSorterTest,
CursorPagingTest, TestRandomCollapseQParserPlugin, SolrCoreTest,
TestPullReplicaErrorHandling, HdfsBasicDistributedZkTest,
SpellCheckCollatorTest, TestBadConfig, TestConfigReload,
AtomicUpdateProcessorFactoryTest, TestHighlightDedupGrouping,
TestEmbeddedSolrServerSchemaAPI, LeaderInitiatedRecoveryOnCommitTest,
TestSubQueryTransformerCrossCore, TestPolicyCloud, URLClassifyProcessorTest,
OverseerTaskQueueTest, SuggestComponentContextFilterQueryTest,
BlockJoinFacetSimpleTest, TestLegacyFieldCache, TestGraphTermsQParserPlugin,
TestNestedDocsSort, HdfsWriteToMultipleCollectionsTest,
DistributedQueryElevationComponentTest, TestSimpleQParserPlugin,
TestShardHandlerFactory, SolrInfoBeanTest, AnalysisAfterCoreReloadTest,
TestDistributedSearch, UtilsToolTest, TestFieldCollectionResource,
TermVectorComponentTest, TestBinaryField, TestMergePolicyConfig, FileUtilsTest,
TestUtils, TestRuleBasedAuthorizationPlugin, CollectionsAPISolrJTest,
TestSolrQueryResponse, DeleteInactiveReplicaTest,
TestRequestStatusCollectionAPI, ZkControllerTest,
TestPerFieldSimilarityWithDefaultOverride, DistributedFacetPivotLongTailTest,
NoCacheHeaderTest, QueryParsingTest, HdfsCollectionsAPIDistributedZkTest,
TestFieldTypeResource, TestMultiWordSynonyms,
DistributedFacetPivotWhiteBoxTest, TestDynamicLoading, HdfsThreadLeakTest,
ChangedSchemaMergeTest, TestHdfsUpdateLog, TestSolrJ, TestCloudInspectUtil,
TestJoin, DistanceUnitsTest, TestConfigsApi, TestXmlQParserPlugin,
TestSolrCloudWithDelegationTokens, TestPayloadCheckQParserPlugin,
TestImpersonationWithHadoopAuth]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestImpersonationWithHadoopAuth -Dtests.seed=B85F2B438F46BB19
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es-UY
-Dtests.timezone=America/La_Paz -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestImpersonationWithHadoopAuth (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth:
[junit4] > 1) Thread[id=9821,
name=jetty-launcher-1038-thread-2-EventThread, state=TIMED_WAITING,
group=TGRP-TestImpersonationWithHadoopAuth]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedNanos(AbstractQueuedSynchronizer.java:1037)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer.tryAcquireSharedNanos(AbstractQueuedSynchronizer.java:1328)
[junit4] > at
java.util.concurrent.CountDownLatch.await(CountDownLatch.java:277)
[junit4] > at
org.apache.curator.CuratorZookeeperClient.internalBlockUntilConnectedOrTimedOut(CuratorZookeeperClient.java:323)
[junit4] > at
org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:105)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] > at
org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] > at
org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] > at
org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:530)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:505)
[junit4] > at
__randomizedtesting.SeedInfo.seed([B85F2B438F46BB19]:0)
[junit4] Completed [255/745 (1!)] on J0 in 43.51s, 10 tests, 1 error, 1
skipped <<< FAILURES!
[...truncated 1010 lines...]
[junit4] Suite: org.apache.solr.cloud.TestTlogReplica
[junit4] 2> 2083309 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_B85F2B438F46BB19-001/init-core-data-001
[junit4] 2> 2083310 WARN
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=146 numCloses=146
[junit4] 2> 2083310 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2083311 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-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> 2083311 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_B85F2B438F46BB19-001/tempDir-001
[junit4] 2> 2083311 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2083311 INFO (Thread-3753) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2083311 INFO (Thread-3753) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2083312 ERROR (Thread-3753) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 2083411 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:34639
[junit4] 2> 2083416 INFO (jetty-launcher-5159-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2083416 INFO (jetty-launcher-5159-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2083418 INFO (jetty-launcher-5159-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@696a057b{/solr,null,AVAILABLE}
[junit4] 2> 2083418 INFO (jetty-launcher-5159-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@16d628f3{/solr,null,AVAILABLE}
[junit4] 2> 2083418 INFO (jetty-launcher-5159-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7556102{HTTP/1.1,[http/1.1]}{127.0.0.1:41869}
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3efd8ee9{HTTP/1.1,[http/1.1]}{127.0.0.1:39303}
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-1) [ ]
o.e.j.s.Server Started @2084828ms
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-2) [ ]
o.e.j.s.Server Started @2084828ms
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41869}
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39303}
[junit4] 2> 2083419 ERROR (jetty-launcher-5159-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2083419 ERROR (jetty-launcher-5159-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-20T00:25:37.399Z
[junit4] 2> 2083419 INFO (jetty-launcher-5159-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-20T00:25:37.399Z
[junit4] 2> 2083420 INFO (jetty-launcher-5159-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2083420 INFO (jetty-launcher-5159-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2083423 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 0x15f372bc90c0002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2083424 INFO (jetty-launcher-5159-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34639/solr
[junit4] 2> 2083425 INFO (jetty-launcher-5159-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34639/solr
[junit4] 2> 2083456 INFO (jetty-launcher-5159-thread-1)
[n:127.0.0.1:41869_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2083456 INFO (jetty-launcher-5159-thread-2)
[n:127.0.0.1:39303_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2083457 INFO (jetty-launcher-5159-thread-1)
[n:127.0.0.1:41869_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:41869_solr
[junit4] 2> 2083457 INFO (jetty-launcher-5159-thread-2)
[n:127.0.0.1:39303_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39303_solr
[junit4] 2> 2083457 INFO (jetty-launcher-5159-thread-1)
[n:127.0.0.1:41869_solr ] o.a.s.c.Overseer Overseer
(id=98858378021568517-127.0.0.1:41869_solr-n_0000000000) starting
[junit4] 2> 2083457 INFO
(zkCallback-5170-thread-1-processing-n:127.0.0.1:41869_solr)
[n:127.0.0.1:41869_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2083458 INFO
(zkCallback-5171-thread-1-processing-n:127.0.0.1:39303_solr)
[n:127.0.0.1:39303_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2083460 INFO (jetty-launcher-5159-thread-1)
[n:127.0.0.1:41869_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41869_solr
[junit4] 2> 2083462 INFO
(zkCallback-5171-thread-1-processing-n:127.0.0.1:39303_solr)
[n:127.0.0.1:39303_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2083462 INFO
(zkCallback-5170-thread-1-processing-n:127.0.0.1:41869_solr)
[n:127.0.0.1:41869_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2083496 INFO (jetty-launcher-5159-thread-1)
[n:127.0.0.1:41869_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41869.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1265092b
[junit4] 2> 2083504 INFO (jetty-launcher-5159-thread-1)
[n:127.0.0.1:41869_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41869.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1265092b
[junit4] 2> 2083504 INFO (jetty-launcher-5159-thread-1)
[n:127.0.0.1:41869_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41869.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1265092b
[junit4] 2> 2083505 INFO (jetty-launcher-5159-thread-1)
[n:127.0.0.1:41869_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_B85F2B438F46BB19-001/tempDir-001/node1/.
[junit4] 2> 2083513 INFO (jetty-launcher-5159-thread-2)
[n:127.0.0.1:39303_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39303.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1265092b
[junit4] 2> 2083517 INFO (jetty-launcher-5159-thread-2)
[n:127.0.0.1:39303_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39303.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1265092b
[junit4] 2> 2083518 INFO (jetty-launcher-5159-thread-2)
[n:127.0.0.1:39303_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39303.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1265092b
[junit4] 2> 2083518 INFO (jetty-launcher-5159-thread-2)
[n:127.0.0.1:39303_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_B85F2B438F46BB19-001/tempDir-001/node2/.
[junit4] 2> 2083535 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2083535 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34639/solr ready
[junit4] 2> 2083536 INFO
(SUITE-TestTlogReplica-seed#[B85F2B438F46BB19]-worker) [ ]
o.a.s.c.TestTlogReplica Using legacyCloud?: false
[junit4] 2> 2083537 INFO (qtp735808144-25422) [n:127.0.0.1:39303_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with
params val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 2083537 INFO (qtp735808144-25422) [n:127.0.0.1:39303_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2083544 INFO
(TEST-TestTlogReplica.testKillTlogReplica-seed#[B85F2B438F46BB19]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testKillTlogReplica
[junit4] 2> 2083545 INFO (qtp735808144-25424) [n:127.0.0.1:39303_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
pullReplicas=0&replicationFactor=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_kill_tlog_replica&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2083545 INFO
(OverseerThreadFactory-8451-thread-1-processing-n:127.0.0.1:41869_solr)
[n:127.0.0.1:41869_solr ] o.a.s.c.CreateCollectionCmd Create collection
tlog_replica_test_kill_tlog_replica
[junit4] 2> 2083648 INFO
(OverseerStateUpdate-98858378021568517-127.0.0.1:41869_solr-n_0000000000)
[n:127.0.0.1:41869_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"tlog_replica_test_kill_tlog_replica",
[junit4] 2> "shard":"shard1",
[junit4] 2>
"core":"tlog_replica_test_kill_tlog_replica_shard1_replica_t1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41869/solr";,
[junit4] 2> "type":"TLOG",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2083649 INFO
(OverseerStateUpdate-98858378021568517-127.0.0.1:41869_solr-n_0000000000)
[n:127.0.0.1:41869_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"tlog_replica_test_kill_tlog_replica",
[junit4] 2> "shard":"shard1",
[junit4] 2>
"core":"tlog_replica_test_kill_tlog_replica_shard1_replica_t2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:39303/solr";,
[junit4] 2> "type":"TLOG",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2083850 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=tlog_replica_test_kill_tlog_replica_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_kill_tlog_replica&shard=shard1&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 2083850 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_kill_tlog_replica_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_kill_tlog_replica&shard=shard1&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 2083851 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2083851 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2083953 INFO
(zkCallback-5171-thread-1-processing-n:127.0.0.1:39303_solr)
[n:127.0.0.1:39303_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/tlog_replica_test_kill_tlog_replica/state.json] for
collection [tlog_replica_test_kill_tlog_replica] has occurred - updating...
(live nodes size: [2])
[junit4] 2> 2083953 INFO
(zkCallback-5170-thread-1-processing-n:127.0.0.1:41869_solr)
[n:127.0.0.1:41869_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/tlog_replica_test_kill_tlog_replica/state.json] for
collection [tlog_replica_test_kill_tlog_replica] has occurred - updating...
(live nodes size: [2])
[junit4] 2> 2083953 INFO
(zkCallback-5171-thread-2-processing-n:127.0.0.1:39303_solr)
[n:127.0.0.1:39303_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/tlog_replica_test_kill_tlog_replica/state.json] for
collection [tlog_replica_test_kill_tlog_replica] has occurred - updating...
(live nodes size: [2])
[junit4] 2> 2083953 INFO
(zkCallback-5170-thread-2-processing-n:127.0.0.1:41869_solr)
[n:127.0.0.1:41869_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/tlog_replica_test_kill_tlog_replica/state.json] for
collection [tlog_replica_test_kill_tlog_replica] has occurred - updating...
(live nodes size: [2])
[junit4] 2> 2084858 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2084858 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2084864 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.s.IndexSchema
[tlog_replica_test_kill_tlog_replica_shard1_replica_t2] Schema name=minimal
[junit4] 2> 2084864 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.s.IndexSchema
[tlog_replica_test_kill_tlog_replica_shard1_replica_t1] Schema name=minimal
[junit4] 2> 2084866 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2084866 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2084866 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.c.CoreContainer
Creating SolrCore 'tlog_replica_test_kill_tlog_replica_shard1_replica_t2' using
configuration from collection tlog_replica_test_kill_tlog_replica, trusted=true
[junit4] 2> 2084866 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.c.CoreContainer
Creating SolrCore 'tlog_replica_test_kill_tlog_replica_shard1_replica_t1' using
configuration from collection tlog_replica_test_kill_tlog_replica, trusted=true
[junit4] 2> 2084867 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39303.solr.core.tlog_replica_test_kill_tlog_replica.shard1.replica_t2'
(registry 'solr.core.tlog_replica_test_kill_tlog_replica.shard1.replica_t2')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1265092b
[junit4] 2> 2084867 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41869.solr.core.tlog_replica_test_kill_tlog_replica.shard1.replica_t1'
(registry 'solr.core.tlog_replica_test_kill_tlog_replica.shard1.replica_t1')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1265092b
[junit4] 2> 2084867 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2084867 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2084867 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.c.SolrCore
[[tlog_replica_test_kill_tlog_replica_shard1_replica_t2] ] Opening new SolrCore
at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_B85F2B438F46BB19-001/tempDir-001/node2/tlog_replica_test_kill_tlog_replica_shard1_replica_t2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_B85F2B438F46BB19-001/tempDir-001/node2/./tlog_replica_test_kill_tlog_replica_shard1_replica_t2/data/]
[junit4] 2> 2084867 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.c.SolrCore
[[tlog_replica_test_kill_tlog_replica_shard1_replica_t1] ] Opening new SolrCore
at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_B85F2B438F46BB19-001/tempDir-001/node1/tlog_replica_test_kill_tlog_replica_shard1_replica_t1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_B85F2B438F46BB19-001/tempDir-001/node1/./tlog_replica_test_kill_tlog_replica_shard1_replica_t1/data/]
[junit4] 2> 2084910 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2084910 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2084911 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 2084911 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 2084912 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@114481d3[tlog_replica_test_kill_tlog_replica_shard1_replica_t1] main]
[junit4] 2> 2084913 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2084913 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2084913 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2084914 INFO
(searcherExecutor-8457-thread-1-processing-n:127.0.0.1:41869_solr
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1 s:shard1
c:tlog_replica_test_kill_tlog_replica r:core_node3) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.c.SolrCore
[tlog_replica_test_kill_tlog_replica_shard1_replica_t1] Registered new searcher
Searcher@114481d3[tlog_replica_test_kill_tlog_replica_shard1_replica_t1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2084914 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1581734050024914944
[junit4] 2> 2084916 INFO (qtp1868420283-25427) [n:127.0.0.1:41869_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node3
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t1]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 2084917 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2084917 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2084919 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
x:tlog_replica_test_kill_tlog_replica_shard1_replica_t2] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 2084919 INFO (qtp735808144-25415) [n:127.0.0.1:39303_solr
c:tlog_replica_test_kill_tlog_replica s:shard1 r:core_node4
[...truncated too long message...]
ad-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 130595 INFO (jetty-closer-162-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3c53a431: rootName =
solr_32967, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@232193cf
[junit4] 2> 130597 INFO (jetty-closer-162-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 130597 INFO (jetty-closer-162-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@9cea19a: rootName =
solr_42115, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@232193cf
[junit4] 2> 130598 INFO (jetty-closer-162-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 130599 INFO (jetty-closer-162-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@370badf3: rootName =
solr_32967, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@271e0373
[junit4] 2> 130601 INFO (jetty-closer-162-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 130601 INFO (jetty-closer-162-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3d856d4: rootName =
solr_42115, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@271e0373
[junit4] 2> 130606 INFO (jetty-closer-162-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:32967_solr
[junit4] 2> 130606 INFO (jetty-closer-162-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:42115_solr
[junit4] 2> 130606 INFO (jetty-closer-162-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 130607 INFO (jetty-closer-162-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 130608 INFO (coreCloseExecutor-299-thread-1)
[n:127.0.0.1:32967_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.c.SolrCore
[collection1_shard2_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@1e899102
[junit4] 2> 130608 INFO (coreCloseExecutor-299-thread-1)
[n:127.0.0.1:32967_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.collection1.shard2.replica_n2, tag=512332034
[junit4] 2> 130609 INFO (coreCloseExecutor-299-thread-1)
[n:127.0.0.1:32967_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@212d7285: rootName =
solr_32967, domain = solr.core.collection1.shard2.replica_n2, service url =
null, agent id = null] for registry solr.core.collection1.shard2.replica_n2 /
com.codahale.metrics.MetricRegistry@1030d0d2
[junit4] 2> 130614 INFO (coreCloseExecutor-299-thread-1)
[n:127.0.0.1:32967_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard2.leader, tag=512332034
[junit4] 2> 130616 INFO
(zkCallback-173-thread-3-processing-n:127.0.0.1:42115_solr)
[n:127.0.0.1:42115_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (0)
[junit4] 2> 130618 INFO (coreCloseExecutor-298-thread-1)
[n:127.0.0.1:42115_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@649baa76
[junit4] 2> 130618 INFO
(zkCallback-172-thread-4-processing-n:127.0.0.1:32967_solr)
[n:127.0.0.1:32967_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (0)
[junit4] 2> 130618 INFO (coreCloseExecutor-298-thread-1)
[n:127.0.0.1:42115_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.collection1.shard1.replica_n1, tag=1687923318
[junit4] 2> 130619 INFO (coreCloseExecutor-298-thread-1)
[n:127.0.0.1:42115_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@626a83f0: rootName =
solr_42115, domain = solr.core.collection1.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.collection1.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@48fe238d
[junit4] 2> 130624 INFO (jetty-closer-162-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=98858417421221893-127.0.0.1:32967_solr-n_0000000000) closing
[junit4] 2> 130624 INFO
(OverseerStateUpdate-98858417421221893-127.0.0.1:32967_solr-n_0000000000)
[n:127.0.0.1:32967_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:32967_solr
[junit4] 2> 130624 WARN
(OverseerAutoScalingTriggerThread-98858417421221893-127.0.0.1:32967_solr-n_0000000000)
[n:127.0.0.1:32967_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 130629 INFO
(zkCallback-173-thread-3-processing-n:127.0.0.1:42115_solr)
[n:127.0.0.1:42115_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:42115_solr
[junit4] 2> 130630 INFO (coreCloseExecutor-298-thread-1)
[n:127.0.0.1:42115_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard1.leader, tag=1687923318
[junit4] 2> 130644 INFO (jetty-closer-162-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@25ee725{/solr,null,UNAVAILABLE}
[junit4] 2> 130660 INFO (jetty-closer-162-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@47056d5d{/solr,null,UNAVAILABLE}
[junit4] 2> 130660 ERROR
(SUITE-GraphExpressionTest-seed#[5A76A4E7F58DD8BD]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 130660 INFO
(SUITE-GraphExpressionTest-seed#[5A76A4E7F58DD8BD]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:34087 34087
[junit4] 2> 135700 INFO (Thread-171) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:34087 34087
[junit4] 2> 135700 WARN (Thread-171) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections/collection1/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 135701 INFO
(SUITE-GraphExpressionTest-seed#[5A76A4E7F58DD8BD]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=651, maxMBSortInHeap=7.474948086284988,
sim=RandomSimilarity(queryNorm=false): {}, locale=fr-CH, timezone=Asia/Tbilisi
[junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=8,threads=1,free=348916256,total=505937920
[junit4] 2> NOTE: All tests run in this JVM:
[UniformDistributionEvaluatorTest, FloorEvaluatorTest, AppendEvaluatorTest,
ArcTangentEvaluatorTest, LargeVolumeBinaryJettyTest, CeilingEvaluatorTest,
TestRetryUtil, LengthEvaluatorTest, AnlysisResponseBaseTest,
LargeVolumeEmbeddedTest, TestEmbeddedSolrServer, GraphTest, TestXMLEscaping,
NormalDistributionEvaluatorTest, ContentStreamTest,
StreamExpressionToExpessionTest, SubtractEvaluatorTest, JsonValidatorTest,
HttpSolrClientSSLAuthConPoolTest, FieldAnalysisResponseTest,
SolrExampleStreamingTest, TestNamedListCodec, TestV2Request,
SolrSchemalessExampleTest, BasicHttpSolrClientTest,
ConcurrentUpdateSolrClientTest, HttpSolrClientBuilderTest,
LBHttpSolrClientBuilderTest, GraphExpressionTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=GraphExpressionTest
-Dtests.seed=5A76A4E7F58DD8BD -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=fr-CH -Dtests.timezone=Asia/Tbilisi -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | GraphExpressionTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes
recoverying - waited for 30 seconds
[junit4] > at
__randomizedtesting.SeedInfo.seed([5A76A4E7F58DD8BD]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:185)
[junit4] > at
org.apache.solr.client.solrj.io.graph.GraphExpressionTest.setupCluster(GraphExpressionTest.java:88)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [130/145 (1!)] on J0 in 67.45s, 0 tests, 1 failure <<<
FAILURES!
[...truncated 46276 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
