Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21434/
Java: 64bit/jdk1.8.0_162 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth
Error Message:
2 threads leaked from SUITE scope at
org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth: 1)
Thread[id=37947, name=jetty-launcher-9541-thread-1-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:531)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506)
2) Thread[id=37945, name=jetty-launcher-9541-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:531)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth:
1) Thread[id=37947, name=jetty-launcher-9541-thread-1-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:531)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506)
2) Thread[id=37945, name=jetty-launcher-9541-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:531)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506)
at __randomizedtesting.SeedInfo.seed([888ABB6D7B597C0C]:0)
FAILED:
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost
Error Message:
The operations computed by ComputePlanAction should not be null
SolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan, null],
BEFORE_ACTION=[compute_plan, null]}
Stack Trace:
java.lang.AssertionError: The operations computed by ComputePlanAction should
not be null SolrClientNodeStateProvider.DEBUG{AFTER_ACTION=[compute_plan,
null], BEFORE_ACTION=[compute_plan, null]}
at
__randomizedtesting.SeedInfo.seed([888ABB6D7B597C0C:B84A5AEFF32B9D50]: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.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost(ComputePlanActionTest.java:291)
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)
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData
Error Message:
Node watch should have fired!
Stack Trace:
java.lang.AssertionError: Node watch should have fired!
at
__randomizedtesting.SeedInfo.seed([888ABB6D7B597C0C:AE1A543F2FD7BA06]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData(TestDistribStateManager.java:256)
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 12299 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager
[junit4] 2> 414248 INFO
(SUITE-TestDistribStateManager-seed#[888ABB6D7B597C0C]-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.autoscaling.sim.TestDistribStateManager_888ABB6D7B597C0C-001/init-core-data-001
[junit4] 2> 414248 WARN
(SUITE-TestDistribStateManager-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 414248 INFO
(SUITE-TestDistribStateManager-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 414249 INFO
(SUITE-TestDistribStateManager-seed#[888ABB6D7B597C0C]-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> 414251 INFO
(TEST-TestDistribStateManager.testGetSetRemoveData-seed#[888ABB6D7B597C0C]) [
] o.a.s.SolrTestCaseJ4 ###Starting testGetSetRemoveData
[junit4] 2> 414251 INFO
(TEST-TestDistribStateManager.testGetSetRemoveData-seed#[888ABB6D7B597C0C]) [
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 414251 INFO (Thread-1860) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 414251 INFO (Thread-1860) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 414255 ERROR (Thread-1860) [ ] 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> 414351 INFO
(TEST-TestDistribStateManager.testGetSetRemoveData-seed#[888ABB6D7B597C0C]) [
] o.a.s.c.ZkTestServer start zk server on port:44699
[junit4] 2> 414353 INFO (zkConnectionManagerCallback-577-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 414353 INFO
(TEST-TestDistribStateManager.testGetSetRemoveData-seed#[888ABB6D7B597C0C]) [
] o.a.s.c.a.s.TestDistribStateManager Using
org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 414358 INFO (zkConnectionManagerCallback-579-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 414358 INFO
(TEST-TestDistribStateManager.testGetSetRemoveData-seed#[888ABB6D7B597C0C]) [
] o.a.s.c.a.s.TestDistribStateManager Using
org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 419359 ERROR
(TEST-TestDistribStateManager.testGetSetRemoveData-seed#[888ABB6D7B597C0C]) [
] 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> 419359 INFO
(TEST-TestDistribStateManager.testGetSetRemoveData-seed#[888ABB6D7B597C0C]) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44699 44699
[junit4] 2> 424379 INFO (Thread-1860) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:44699 44699
[junit4] 2> 424380 INFO
(TEST-TestDistribStateManager.testGetSetRemoveData-seed#[888ABB6D7B597C0C]) [
] o.a.s.SolrTestCaseJ4 ###Ending testGetSetRemoveData
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestDistribStateManager -Dtests.method=testGetSetRemoveData
-Dtests.seed=888ABB6D7B597C0C -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=zh-TW -Dtests.timezone=Africa/Maseru -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 10.1s J2 | TestDistribStateManager.testGetSetRemoveData <<<
[junit4] > Throwable #1: java.lang.AssertionError: Node watch should have
fired!
[junit4] > at
__randomizedtesting.SeedInfo.seed([888ABB6D7B597C0C:AE1A543F2FD7BA06]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.sim.TestDistribStateManager.testGetSetRemoveData(TestDistribStateManager.java:256)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 424383 INFO
(TEST-TestDistribStateManager.testHasData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testHasData
[junit4] 2> 424383 INFO
(TEST-TestDistribStateManager.testHasData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 424383 INFO (Thread-1861) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 424383 INFO (Thread-1861) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 424385 ERROR (Thread-1861) [ ] 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> 424484 INFO
(TEST-TestDistribStateManager.testHasData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.ZkTestServer start zk server on port:43835
[junit4] 2> 424486 INFO (zkConnectionManagerCallback-581-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 424486 INFO
(TEST-TestDistribStateManager.testHasData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.a.s.TestDistribStateManager Using
org.apache.solr.client.solrj.impl.ZkDistribStateManager
[junit4] 2> 424490 ERROR
(TEST-TestDistribStateManager.testHasData-seed#[888ABB6D7B597C0C]) [ ]
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> 424491 INFO
(TEST-TestDistribStateManager.testHasData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:43835 43835
[junit4] 2> 430264 INFO (Thread-1861) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:43835 43835
[junit4] 2> 430265 INFO
(TEST-TestDistribStateManager.testHasData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testHasData
[junit4] 2> 430266 INFO
(TEST-TestDistribStateManager.testCreateMode-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testCreateMode
[junit4] 2> 430266 INFO
(TEST-TestDistribStateManager.testCreateMode-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.a.s.TestDistribStateManager Using
org.apache.solr.cloud.autoscaling.sim.SimDistribStateManager
[junit4] 2> 430267 INFO
(TEST-TestDistribStateManager.testCreateMode-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.a.s.TestDistribStateManager Using
org.apache.solr.cloud.autoscaling.sim.SimDistribStateManager
[junit4] 2> 430267 INFO
(TEST-TestDistribStateManager.testCreateMode-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testCreateMode
[junit4] 2> 430268 INFO
(TEST-TestDistribStateManager.testListData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testListData
[junit4] 2> 430268 INFO
(TEST-TestDistribStateManager.testListData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.a.s.TestDistribStateManager Using
org.apache.solr.cloud.autoscaling.sim.SimDistribStateManager
[junit4] 2> 430268 INFO
(TEST-TestDistribStateManager.testListData-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testListData
[junit4] 2> 430269 INFO
(TEST-TestDistribStateManager.testMulti-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testMulti
[junit4] 2> 430269 INFO
(TEST-TestDistribStateManager.testMulti-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.a.s.TestDistribStateManager Using
org.apache.solr.cloud.autoscaling.sim.SimDistribStateManager
[junit4] 2> 430269 INFO
(TEST-TestDistribStateManager.testMulti-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testMulti
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.sim.TestDistribStateManager_888ABB6D7B597C0C-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=717, maxMBSortInHeap=6.454719835647284,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@79dee87),
locale=zh-TW, timezone=Africa/Maseru
[junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation
1.8.0_162 (64-bit)/cpus=8,threads=1,free=231662680,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [UtilsToolTest,
TestSortByMinMaxFunction, CopyFieldTest, TestLargeCluster, TestCloudPivotFacet,
TestRebalanceLeaders, TestUniqueKeyFieldResource, DistanceFunctionTest,
TestLeaderInitiatedRecoveryThread, ConnectionReuseTest, ChangedSchemaMergeTest,
TestAnalyzedSuggestions, TestCollectionAPIs, ReturnFieldsTest,
DirectoryFactoryTest, WordBreakSolrSpellCheckerTest, UnloadDistributedZkTest,
TestNonDefinedSimilarityFactory, TestCloudNestedDocsSort,
TestGraphTermsQParserPlugin, TestFiltering, MoveReplicaHDFSFailoverTest,
HdfsNNFailoverTest, StressHdfsTest, TolerantUpdateProcessorTest,
SpatialFilterTest, SparseHLLTest, ExecutePlanActionTest,
AnalysisErrorHandlingTest, HdfsTlogReplayBufferedWhileIndexingTest,
ConcurrentDeleteAndCreateCollectionTest, CloneFieldUpdateProcessorFactoryTest,
TestDistribStateManager]
[junit4] Completed [77/776 (1!)] on J2 in 16.03s, 5 tests, 1 failure <<<
FAILURES!
[...truncated 1720 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[junit4] 2> 2791461 INFO
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-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.autoscaling.ComputePlanActionTest_888ABB6D7B597C0C-001/init-core-data-001
[junit4] 2> 2791461 WARN
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=20 numCloses=20
[junit4] 2> 2791462 INFO
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2791463 INFO
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2791464 INFO
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_888ABB6D7B597C0C-001/tempDir-001
[junit4] 2> 2791464 INFO
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2791464 INFO (Thread-9251) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2791464 INFO (Thread-9251) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2791465 ERROR (Thread-9251) [ ] 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> 2791564 INFO
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:36517
[junit4] 2> 2791566 INFO (zkConnectionManagerCallback-4825-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791568 INFO (jetty-launcher-4822-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z,
git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2791569 INFO (jetty-launcher-4822-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2791569 INFO (jetty-launcher-4822-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2791569 INFO (jetty-launcher-4822-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2791569 INFO (jetty-launcher-4822-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@9f4174f{/solr,null,AVAILABLE}
[junit4] 2> 2791569 INFO (jetty-launcher-4822-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7b0720ef{HTTP/1.1,[http/1.1]}{127.0.0.1:38565}
[junit4] 2> 2791569 INFO (jetty-launcher-4822-thread-1) [ ]
o.e.j.s.Server Started @2793061ms
[junit4] 2> 2791570 INFO (jetty-launcher-4822-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38565}
[junit4] 2> 2791570 ERROR (jetty-launcher-4822-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2791570 INFO (jetty-launcher-4822-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2791570 INFO (jetty-launcher-4822-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2791570 INFO (jetty-launcher-4822-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2791570 INFO (jetty-launcher-4822-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-11T01:42:33.885Z
[junit4] 2> 2791571 INFO (zkConnectionManagerCallback-4827-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791571 INFO (jetty-launcher-4822-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2791578 INFO (jetty-launcher-4822-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36517/solr
[junit4] 2> 2791579 INFO (zkConnectionManagerCallback-4831-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791580 INFO
(zkConnectionManagerCallback-4833-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791608 INFO (jetty-launcher-4822-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2791608 INFO (jetty-launcher-4822-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:38565_solr
[junit4] 2> 2791609 INFO (jetty-launcher-4822-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer Overseer
(id=72128144473653251-127.0.0.1:38565_solr-n_0000000000) starting
[junit4] 2> 2791611 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 2791611 INFO (jetty-launcher-4822-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38565_solr
[junit4] 2> 2791611 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 2791612 INFO
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2791612 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 2791612 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38565_solr]
[junit4] 2> 2791612 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 2791612 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 2791613 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2791613 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2791613 DEBUG (ScheduledTrigger-9733-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 2791678 INFO (jetty-launcher-4822-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38565.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@d61b478
[junit4] 2> 2791686 INFO (jetty-launcher-4822-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38565.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@d61b478
[junit4] 2> 2791686 INFO (jetty-launcher-4822-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38565.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@d61b478
[junit4] 2> 2791687 INFO (jetty-launcher-4822-thread-1)
[n:127.0.0.1:38565_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.autoscaling.ComputePlanActionTest_888ABB6D7B597C0C-001/tempDir-001/node1/.
[junit4] 2> 2791699 INFO (zkConnectionManagerCallback-4837-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791701 INFO (zkConnectionManagerCallback-4841-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791701 INFO
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2791702 INFO
(SUITE-ComputePlanActionTest-seed#[888ABB6D7B597C0C]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36517/solr ready
[junit4] 2> 2791709 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testNodeLost
[junit4] 2> 2791709 DEBUG
(zkCallback-4832-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 2791710 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 2791710 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2791711 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2791711 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2791711 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2791713 INFO (qtp301087334-28088) [n:127.0.0.1:38565_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2791713 DEBUG (qtp301087334-28082) [n:127.0.0.1:38565_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2791713 INFO (qtp301087334-28082) [n:127.0.0.1:38565_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 2791714 DEBUG
(zkCallback-4832-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 2791714 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 2791714 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2791714 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2791714 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2791715 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2791715 DEBUG (qtp301087334-28086) [n:127.0.0.1:38565_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2791716 INFO (qtp301087334-28086) [n:127.0.0.1:38565_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2791716 DEBUG
(zkCallback-4832-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 2791716 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 2791716 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2791716 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2791716 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 2791716 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z,
git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2791717 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2791717 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2791717 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2791717 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@49d09d4f{/solr,null,AVAILABLE}
[junit4] 2> 2791717 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@72b11364{HTTP/1.1,[http/1.1]}{127.0.0.1:46435}
[junit4] 2> 2791718 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.e.j.s.Server Started @2793209ms
[junit4] 2> 2791718 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46435}
[junit4] 2> 2791718 ERROR
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2791718 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2791718 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2791718 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2791718 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-11T01:42:34.033Z
[junit4] 2> 2791719 INFO (zkConnectionManagerCallback-4844-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791720 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2791724 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36517/solr
[junit4] 2> 2791725 INFO (zkConnectionManagerCallback-4848-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791727 INFO
(zkConnectionManagerCallback-4850-thread-1-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2791729 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C])
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2791730 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C])
[n:127.0.0.1:46435_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2791731 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C])
[n:127.0.0.1:46435_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46435_solr
[junit4] 2> 2791731 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:46435_solr"} current state version: 0
[junit4] 2> 2791731 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for
node: 127.0.0.1:46435_solr
[junit4] 2> 2791731 INFO
(zkCallback-4832-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2791731 INFO (zkCallback-4840-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2791732 INFO
(zkCallback-4849-thread-1-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2791732 INFO (zkCallback-4840-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2791770 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C])
[n:127.0.0.1:46435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46435.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@d61b478
[junit4] 2> 2791784 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C])
[n:127.0.0.1:46435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46435.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@d61b478
[junit4] 2> 2791784 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C])
[n:127.0.0.1:46435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46435.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@d61b478
[junit4] 2> 2791785 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[888ABB6D7B597C0C])
[n:127.0.0.1:46435_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.autoscaling.ComputePlanActionTest_888ABB6D7B597C0C-001/tempDir-001/node2/.
[junit4] 2> 2791805 INFO (qtp301087334-28088) [n:127.0.0.1:38565_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2791806 INFO (qtp848455284-28122) [n:127.0.0.1:46435_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2791807 DEBUG (qtp301087334-28118) [n:127.0.0.1:38565_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2791807 INFO (qtp301087334-28118) [n:127.0.0.1:38565_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 2791807 DEBUG
(zkCallback-4832-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 2791807 INFO (qtp301087334-28086) [n:127.0.0.1:38565_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=testNodeLost&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2791807 DEBUG
(zkCallback-4832-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:46435_solr, 127.0.0.1:38565_solr]
[junit4] 2> 2791808 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 2791808 INFO
(OverseerThreadFactory-9735-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testNodeLost
[junit4] 2> 2791808 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2791810 DEBUG
(OverseerAutoScalingTriggerThread-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 2791811 DEBUG (ScheduledTrigger-9733-thread-1)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 2
[junit4] 2> 2791811 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2791812 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2791815 INFO
(OverseerThreadFactory-9735-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession,
curr-time 1076514567 sessionWrapper.createTime 1076514564748974,
this.sessionWrapper.createTime 1076514564748974
[junit4] 2> 2791823 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "name":"testNodeLost",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf",
[junit4] 2> "numShards":"1",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 2791824 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ClusterStateMutator building a new
cName: testNodeLost
[junit4] 2> 2791824 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/testNodeLost/state.json
[junit4] 2> 2791919 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 2791919 INFO
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2791919 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 2791919 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 2791919 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 2791920 INFO
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2791920 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 2791920 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 2792020 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeLost/state.json version: 0
[junit4] 2> 2792121 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeLost_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeLost&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2792121 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeLost_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeLost&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2792121 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2792121 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2792123 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "node_name":"127.0.0.1:46435_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2792123 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "node_name":"127.0.0.1:46435_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 2792123 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node3:{"core":"testNodeLost_shard1_replica_n1","base_url":"http://127.0.0.1:46435/solr","node_name":"127.0.0.1:46435_solr","state":"down","type":"NRT"}
[junit4] 2> 2792123 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeLost//collections/testNodeLost/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "node_name":"127.0.0.1:46435_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 2792124 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 2,
message = {
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "node_name":"127.0.0.1:38565_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2792124 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "node_name":"127.0.0.1:38565_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 2792124 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node4:{"core":"testNodeLost_shard1_replica_n2","base_url":"http://127.0.0.1:38565/solr","node_name":"127.0.0.1:38565_solr","state":"down","type":"NRT"}
[junit4] 2> 2792124 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeLost//collections/testNodeLost/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "node_name":"127.0.0.1:46435_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "node_name":"127.0.0.1:38565_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 2792224 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeLost/state.json version: 1
[junit4] 2> 2792225 INFO
(zkCallback-4832-thread-1-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2792225 INFO
(zkCallback-4849-thread-1-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2792225 INFO
(zkCallback-4832-thread-2-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2792225 INFO
(zkCallback-4849-thread-2-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2792812 DEBUG (ScheduledTrigger-9733-thread-2)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 2
[junit4] 2> 2793129 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2793129 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2793133 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.s.IndexSchema [testNodeLost_shard1_replica_n2] Schema name=minimal
[junit4] 2> 2793133 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.s.IndexSchema [testNodeLost_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2793135 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2793135 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2793135 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeLost_shard1_replica_n1' using
configuration from collection testNodeLost, trusted=true
[junit4] 2> 2793135 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeLost_shard1_replica_n2' using
configuration from collection testNodeLost, trusted=true
[junit4] 2> 2793136 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38565.solr.core.testNodeLost.shard1.replica_n2' (registry
'solr.core.testNodeLost.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@d61b478
[junit4] 2> 2793136 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46435.solr.core.testNodeLost.shard1.replica_n1' (registry
'solr.core.testNodeLost.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@d61b478
[junit4] 2> 2793136 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2793136 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2793136 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.c.SolrCore [[testNodeLost_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_888ABB6D7B597C0C-001/tempDir-001/node1/testNodeLost_shard1_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_888ABB6D7B597C0C-001/tempDir-001/node1/./testNodeLost_shard1_replica_n2/data/]
[junit4] 2> 2793136 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.SolrCore [[testNodeLost_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_888ABB6D7B597C0C-001/tempDir-001/node2/testNodeLost_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ComputePlanActionTest_888ABB6D7B597C0C-001/tempDir-001/node2/./testNodeLost_shard1_replica_n1/data/]
[junit4] 2> 2793164 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2793164 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2793164 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2793164 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2793165 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2793165 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2793165 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2793165 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2793166 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1eb91955[testNodeLost_shard1_replica_n1] main]
[junit4] 2> 2793166 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@61de42fb[testNodeLost_shard1_replica_n2] main]
[junit4] 2> 2793167 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2793167 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2793167 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2793167 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2793167 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2793168 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2793168 INFO
(searcherExecutor-9745-thread-1-processing-n:127.0.0.1:38565_solr
x:testNodeLost_shard1_replica_n2 s:shard1 c:testNodeLost r:core_node4)
[n:127.0.0.1:38565_solr c:testNodeLost s:shard1 r:core_node4
x:testNodeLost_shard1_replica_n2] o.a.s.c.SolrCore
[testNodeLost_shard1_replica_n2] Registered new searcher
Searcher@61de42fb[testNodeLost_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2793168 INFO
(searcherExecutor-9744-thread-1-processing-n:127.0.0.1:46435_solr
x:testNodeLost_shard1_replica_n1 s:shard1 c:testNodeLost r:core_node3)
[n:127.0.0.1:46435_solr c:testNodeLost s:shard1 r:core_node3
x:testNodeLost_shard1_replica_n1] o.a.s.c.SolrCore
[testNodeLost_shard1_replica_n1] Registered new searcher
Searcher@1eb91955[testNodeLost_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2793168 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1592066945038942208
[junit4] 2> 2793168 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1592066945038942208
[junit4] 2> 2793171 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.c.ZkShardTerms Failed to save terms, version is not match, retrying
[junit4] 2> 2793172 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2793172 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2793172 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost"} current state version: 0
[junit4] 2> 2793172 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:46435/solr/testNodeLost_shard1_replica_n1/
[junit4] 2> 2793173 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.u.PeerSync PeerSync: core=testNodeLost_shard1_replica_n1
url=http://127.0.0.1:46435/solr START
replicas=[http://127.0.0.1:38565/solr/testNodeLost_shard1_replica_n2/]
nUpdates=100
[junit4] 2> 2793174 INFO (qtp301087334-28120) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.c.S.Request [testNodeLost_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2793273 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeLost/state.json version: 2
[junit4] 2> 2793273 INFO
(zkCallback-4832-thread-2-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793273 INFO
(zkCallback-4849-thread-2-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793273 INFO
(zkCallback-4849-thread-1-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793273 INFO
(zkCallback-4832-thread-3-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793473 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.u.PeerSync PeerSync: core=testNodeLost_shard1_replica_n1
url=http://127.0.0.1:46435/solr DONE. We have no versions. sync failed.
[junit4] 2> 2793473 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 2793473 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2793473 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2793476 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:46435/solr/testNodeLost_shard1_replica_n1/ shard1
[junit4] 2> 2793476 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 2793577 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeLost/state.json version: 3
[junit4] 2> 2793577 INFO
(zkCallback-4832-thread-2-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793577 INFO
(zkCallback-4849-thread-1-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793577 INFO
(zkCallback-4849-thread-2-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793577 INFO
(zkCallback-4832-thread-3-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793627 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2793628 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "node_name":"127.0.0.1:46435_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2793628 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "node_name":"127.0.0.1:46435_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 2793628 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node3:{"core":"testNodeLost_shard1_replica_n1","base_url":"http://127.0.0.1:46435/solr","node_name":"127.0.0.1:46435_solr","state":"active","type":"NRT","leader":"true"}
[junit4] 2> 2793628 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeLost//collections/testNodeLost/state.json/4)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "node_name":"127.0.0.1:46435_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "node_name":"127.0.0.1:38565_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 2793633 INFO (qtp848455284-28126) [n:127.0.0.1:46435_solr
c:testNodeLost s:shard1 r:core_node3 x:testNodeLost_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeLost_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeLost&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1512
[junit4] 2> 2793729 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeLost/state.json version: 4
[junit4] 2> 2793729 INFO
(zkCallback-4832-thread-3-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793729 INFO
(zkCallback-4849-thread-2-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793729 INFO
(zkCallback-4832-thread-2-processing-n:127.0.0.1:38565_solr)
[n:127.0.0.1:38565_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793729 INFO
(zkCallback-4849-thread-1-processing-n:127.0.0.1:46435_solr)
[n:127.0.0.1:46435_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testNodeLost/state.json] for collection [testNodeLost] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2793812 DEBUG (ScheduledTrigger-9733-thread-2)
[n:127.0.0.1:38565_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 2
[junit4] 2> 2794174 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "node_name":"127.0.0.1:38565_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 2794174 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "node_name":"127.0.0.1:38565_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeLost",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 2794174 INFO (qtp301087334-28087) [n:127.0.0.1:38565_solr
c:testNodeLost s:shard1 r:core_node4 x:testNodeLost_shard1_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeLost_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeLost&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2053
[junit4] 2> 2794174 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Will update state for
replica:
core_node4:{"core":"testNodeLost_shard1_replica_n2","base_url":"http://127.0.0.1:38565/solr","node_name":"127.0.0.1:38565_solr","state":"active","type":"NRT"}
[junit4] 2> 2794174 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeLost//collections/testNodeLost/state.json/5)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n1",
[junit4] 2> "base_url":"http://127.0.0.1:46435/solr";,
[junit4] 2> "node_name":"127.0.0.1:46435_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeLost_shard1_replica_n2",
[junit4] 2> "base_url":"http://127.0.0.1:38565/solr";,
[junit4] 2> "node_name":"127.0.0.1:38565_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 2794176 INFO (qtp301087334-28086) [n:127.0.0.1:38565_solr
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 2794275 DEBUG
(OverseerStateUpdate-72128144473653251-127.0.0.1:38565_solr-n_0000000000)
[n:127.0.0.1:38565_solr ] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeLost/state.json version: 5
[junit4] 2> 2794275 INFO
(zkCallback-4849-thread-1-processing-n:127.0.0.1:46435_solr) [n:127.0.0.1:4
[...truncated too long message...]
06)
[junit4] 2> Feb 11, 2018 1:50:42 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Lucene70,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1418f58b),
locale=en-CA, timezone=America/Martinique
[junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation
1.8.0_162 (64-bit)/cpus=8,threads=1,free=148029744,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestClusterStateProvider,
TestHashQParserPlugin, AutoScalingHandlerTest, HdfsRecoverLeaseTest,
ConfigureRecoveryStrategyTest, TestUtilizeNode, ZkCLITest, TestSchemaManager,
DistributedQueryElevationComponentTest, PKIAuthenticationIntegrationTest,
SolrXmlInZkTest, SolrGangliaReporterTest, TestSchemaVersionResource,
BasicDistributedZkTest, DistributedTermsComponentTest, TestHdfsUpdateLog,
RollingRestartTest, CoreMergeIndexesAdminHandlerTest, TestBinaryField,
TestFieldCacheSortRandom, ImplicitSnitchTest, TestSolrQueryParser,
TestComponentsName, DeleteLastCustomShardedReplicaTest, ZkNodePropsTest,
SolrRequestParserTest, HDFSCollectionsAPITest, TestCloudPseudoReturnFields,
SolrIndexMetricsTest, XmlUpdateRequestHandlerTest, ExitableDirectoryReaderTest,
TestDistribDocBasedVersion, DistributedVersionInfoTest,
TestEmbeddedSolrServerSchemaAPI, TestDynamicFieldCollectionResource,
TestPhraseSuggestions, TestClusterProperties, TestConfigSetsAPIZkFailure,
TestDistribIDF, TestLRUStatsCache, TestWordDelimiterFilterFactory,
TestConfigSetProperties, LIRRollingUpdatesTest, ActionThrottleTest,
TestNamedUpdateProcessors, ChaosMonkeySafeLeaderWithPullReplicasTest,
BlockJoinFacetSimpleTest, DistributedQueryComponentOptimizationTest,
HdfsSyncSliceTest, TestWriterPerf, CdcrReplicationHandlerTest,
ExternalFileFieldSortTest, MetricUtilsTest, HighlighterConfigTest,
SolrIndexSplitterTest, TestSolrConfigHandler, OutputWriterTest,
HdfsUnloadDistributedZkTest, ZkSolrClientTest, TestUseDocValuesAsStored,
TestExportWriter, TermVectorComponentDistributedTest,
TestPerFieldSimilarityWithDefaultOverride, TestDocumentBuilder, BJQParserTest,
TestFileDictionaryLookup, TestPrepRecovery, TestRequestForwarding,
OpenExchangeRatesOrgProviderTest, TestNodeAddedTrigger,
TestClusterStateMutator, DirectUpdateHandlerTest, TestIBSimilarityFactory,
HttpSolrCallGetCoreTest, FieldAnalysisRequestHandlerTest, TestNoOpRegenerator,
TestReloadDeadlock, TestCloudInspectUtil, TestNumericRangeQuery64,
BadIndexSchemaTest, UUIDUpdateProcessorFallbackTest, TestCoreBackup,
TestShortCircuitedRequests, TestLMDirichletSimilarityFactory, TestConfigsApi,
StatsComponentTest, SolrMetricManagerTest, DefaultValueUpdateProcessorTest,
ShardRoutingTest, TestLockTree, CurrencyFieldTypeTest, SystemLogListenerTest,
TestMaxScoreQueryParser, TestSimDistributedQueue,
TestSubQueryTransformerDistrib, LargeFieldTest, TestSolrCLIRunExample,
TestLegacyFieldCache, TestPHPSerializedResponseWriter, TestReqParamsAPI,
DocumentAnalysisRequestHandlerTest, BitVectorTest, IndexBasedSpellCheckerTest,
TestJoin, OpenCloseCoreStressTest, UpdateParamsTest, TestBulkSchemaConcurrent,
BinaryUpdateRequestHandlerTest, NodeMutatorTest, TestRandomFlRTGCloud,
HdfsChaosMonkeyNothingIsSafeTest, TestInfoStreamLogging, TestFastOutputStream,
TestCustomStream, TestConfig, TestHdfsCloudBackupRestore, MoveReplicaHDFSTest,
TestLegacyTerms, CursorPagingTest, TestRuleBasedAuthorizationPlugin,
EnumFieldTest, SpellCheckCollatorTest, TestLuceneIndexBackCompat,
OverseerCollectionConfigSetProcessorTest, SuggesterWFSTTest,
TestSolrCoreSnapshots, LeaderFailureAfterFreshStartTest, MoveReplicaTest,
OverseerStatusTest, RecoveryAfterSoftCommitTest,
TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry,
TestOnReconnectListenerSupport, TestSkipOverseerOperations,
TestStressCloudBlindAtomicUpdates, TestStressLiveNodes, TestTlogReplica,
SimpleCollectionCreateDeleteTest, TestCollectionAPI,
TestLocalFSCloudBackupRestore, TestReplicaProperties,
AutoAddReplicasPlanActionTest, HdfsAutoAddReplicasIntegrationTest,
TestExecutePlanAction, TestNodeLostTrigger, TestPolicyCloud,
CdcrBidirectionalTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest,
RulesTest, TestConfigSets, TestCorePropertiesReload, TestNRTOpen,
TestReloadAndDeleteDocs, ResponseBuilderTest, SearchHandlerTest,
TestBlobHandler, TestReplicationHandlerBackup, TestSQLHandler,
AutoscalingHistoryHandlerTest, DistributedFacetPivotLargeTest,
DistributedFacetPivotSmallTest, InfixSuggestersTest,
ShufflingReplicaListTransformerTest, SuggestComponentTest,
TestDistributedStatsComponentCardinality, JvmMetricsTest,
SolrJmxReporterCloudTest, SolrCloudReportersTest, SubstringBytesRefFilterTest,
TestMacroExpander, TestMacros, SmileWriterTest, TestRawResponseWriter,
TestSolrQueryResponse, TestSubQueryTransformer, TestRestManager,
TestCopyFieldCollectionResource, TestFieldCollectionResource,
TestFieldResource, TestFieldTypeCollectionResource, TestFieldTypeResource,
TestSchemaResource, TestSchemaSimilarityResource, TestManagedStopFilterFactory,
TestCollationFieldDocValues, TestHalfAndHalfDocValues, TestManagedSchemaAPI,
SortSpecParsingTest, TestCustomSort, TestLegacyNumericRangeQueryBuilder,
TestOverriddenPrefixQueryForCustomFieldType, TestReRankQParserPlugin,
TestSmileRequest, TestJsonFacets, TestImpersonationWithHadoopAuth]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestImpersonationWithHadoopAuth -Dtests.seed=888ABB6D7B597C0C
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-CA
-Dtests.timezone=America/Martinique -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestImpersonationWithHadoopAuth (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.security.hadoop.TestImpersonationWithHadoopAuth:
[junit4] > 1) Thread[id=37947,
name=jetty-launcher-9541-thread-1-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:531)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506)
[junit4] > 2) Thread[id=37945,
name=jetty-launcher-9541-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:531)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506)
[junit4] > at
__randomizedtesting.SeedInfo.seed([888ABB6D7B597C0C]:0)
[junit4] Completed [765/776 (3!)] on J0 in 43.10s, 10 tests, 1 error, 1
skipped <<< FAILURES!
[...truncated 48316 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
